builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-057 starttime: 1504345445.69 results: success (0) revision: b01a7e57425b5fe791ab091f5c33e069890753fb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:05.688577) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:05.689028) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:05.689298) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:05.707348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:05.707671) ========= 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-1504345444.778711-834712415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 02:44:05-- 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% 45.3M=0s 2017-09-02 02:44:05 (45.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123219 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:05.936970) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:05.937270) ========= 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-1504345444.778711-834712415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021208 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:05.975943) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:05.976477) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b01a7e57425b5fe791ab091f5c33e069890753fb --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 b01a7e57425b5fe791ab091f5c33e069890753fb --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-1504345444.778711-834712415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 02:44:06,029 truncating revision to first 12 chars 2017-09-02 02:44:06,029 Setting DEBUG logging. 2017-09-02 02:44:06,029 attempt 1/10 2017-09-02 02:44:06,029 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b01a7e57425b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-02 02:44:06,500 unpacking tar archive at: mozilla-central-b01a7e57425b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.818940 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:06.812412) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:06.812727) ========= script_repo_revision: b01a7e57425b5fe791ab091f5c33e069890753fb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:06.813149) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:06.813410) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 02:44:06.826784) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-02 02:44:06.827390) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:44:07 INFO - MultiFileLogger online at 20170902 02:44:07 in /builds/slave/test 02:44:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 02:44:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:44:07 INFO - {'append_to_log': False, 02:44:07 INFO - 'base_work_dir': '/builds/slave/test', 02:44:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:44:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:44:07 INFO - 'branch': 'Firefox-Non-PGO', 02:44:07 INFO - 'buildbot_json_path': 'buildprops.json', 02:44:07 INFO - 'code_coverage': False, 02:44:07 INFO - 'config_files': ('talos/linux_config.py',), 02:44:07 INFO - 'default_actions': ('clobber', 02:44:07 INFO - 'read-buildbot-config', 02:44:07 INFO - 'download-and-extract', 02:44:07 INFO - 'populate-webroot', 02:44:07 INFO - 'create-virtualenv', 02:44:07 INFO - 'install', 02:44:07 INFO - 'setup-mitmproxy', 02:44:07 INFO - 'run-tests'), 02:44:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:44:07 INFO - 'disable_ccov_upload': False, 02:44:07 INFO - 'disable_stylo': False, 02:44:07 INFO - 'download_minidump_stackwalk': True, 02:44:07 INFO - 'download_symbols': 'ondemand', 02:44:07 INFO - 'enable_stylo': False, 02:44:07 INFO - 'enable_webrender': False, 02:44:07 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:44:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:44:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:44:07 INFO - 'gecko_profile': False, 02:44:07 INFO - 'gecko_profile_interval': 0, 02:44:07 INFO - 'installer_path': 'installer.exe', 02:44:07 INFO - 'log_level': 'info', 02:44:07 INFO - 'log_name': 'talos', 02:44:07 INFO - 'log_to_console': True, 02:44:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:44:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:44:07 INFO - 'opt_config_files': (), 02:44:07 INFO - 'pip_index': False, 02:44:07 INFO - 'suite': 'dromaeojs-e10s', 02:44:07 INFO - 'system_bits': '32', 02:44:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:44:07 INFO - 'title': 'talos-linux64-ix-057', 02:44:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:44:07 INFO - 'use_talos_json': True, 02:44:07 INFO - 'verify': 'False', 02:44:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:44:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:44:07 INFO - 'work_dir': 'build'} 02:44:07 INFO - [mozharness: 2017-09-02 09:44:07.027445Z] Running clobber step. 02:44:07 INFO - Running pre-action listener: _resource_record_pre_action 02:44:07 INFO - Running main action method: clobber 02:44:07 INFO - rmtree: /builds/slave/test/build 02:44:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:44:08 INFO - Running post-action listener: _resource_record_post_action 02:44:08 INFO - [mozharness: 2017-09-02 09:44:08.132595Z] Finished clobber step (success) 02:44:08 INFO - [mozharness: 2017-09-02 09:44:08.132693Z] Running read-buildbot-config step. 02:44:08 INFO - Running pre-action listener: _resource_record_pre_action 02:44:08 INFO - Running main action method: read_buildbot_config 02:44:08 INFO - Using buildbot properties: 02:44:08 INFO - { 02:44:08 INFO - "project": "", 02:44:08 INFO - "product": "firefox", 02:44:08 INFO - "who": "archaeopteryx@coole-files.de", 02:44:08 INFO - "installer_path": "public/build/target.tar.bz2", 02:44:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:44:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:44:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 02:44:08 INFO - "stage_platform": "linux64", 02:44:08 INFO - "basedir": "/builds/slave/test", 02:44:08 INFO - "buildnumber": 1110, 02:44:08 INFO - "platform": "ubuntu64_hw", 02:44:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:44:08 INFO - "slavebuilddir": "test", 02:44:08 INFO - "taskId": "dnUXshmwTmy42oCKuAfJ-g", 02:44:08 INFO - "branch": "mozilla-central", 02:44:08 INFO - "script_repo_revision": "production", 02:44:08 INFO - "revision": "b01a7e57425b5fe791ab091f5c33e069890753fb", 02:44:08 INFO - "slavename": "talos-linux64-ix-057", 02:44:08 INFO - "repo_path": "mozilla-central" 02:44:08 INFO - } 02:44:08 INFO - Finding installer, test and symbols from parent task. 02:44:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dnUXshmwTmy42oCKuAfJ-g'}, attempt #1 02:44:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dnUXshmwTmy42oCKuAfJ-g'}, attempt #1 02:44:09 INFO - Task dependencies: ftQy0px5TK6bx82a3KX9nw 02:44:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw'}, attempt #1 02:44:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.tar.bz2 02:44:09 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.test_packages.json 02:44:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.crashreporter-symbols.zip 02:44:09 INFO - Running post-action listener: _resource_record_post_action 02:44:09 INFO - [mozharness: 2017-09-02 09:44:09.482181Z] Finished read-buildbot-config step (success) 02:44:09 INFO - [mozharness: 2017-09-02 09:44:09.482398Z] Running download-and-extract step. 02:44:09 INFO - Running pre-action listener: _resource_record_pre_action 02:44:09 INFO - Running main action method: download_and_extract 02:44:09 INFO - mkdir: /builds/slave/test/build/tests 02:44:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:09 INFO - trying https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.test_packages.json 02:44:09 INFO - Downloading https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:44:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:44:10 INFO - Downloaded 989 bytes. 02:44:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:44:10 INFO - Using the following test package requirements: 02:44:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:44:10 INFO - u'common': [u'target.common.tests.zip'], 02:44:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:44:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:44:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:44:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:44:10 INFO - u'mozbase': [u'target.common.tests.zip'], 02:44:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:44:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:44:10 INFO - u'web-platform': [u'target.common.tests.zip', 02:44:10 INFO - u'target.web-platform.tests.tar.gz'], 02:44:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:44:10 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:44:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.common.tests.zip 02:44:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:44:10 INFO - Fetch https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.common.tests.zip into memory 02:44:11 INFO - Content-Length response header: 55022366 02:44:11 INFO - Bytes received: 55022366 02:44:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:44:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.common.tests.zip 02:44:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:44:21 INFO - Fetch https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.common.tests.zip into memory 02:44:23 INFO - Content-Length response header: 55022366 02:44:23 INFO - Bytes received: 55022366 02:44:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.talos.tests.zip 02:44:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:44:34 INFO - Fetch https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.talos.tests.zip into memory 02:44:35 INFO - Content-Length response header: 14496650 02:44:35 INFO - Bytes received: 14496650 02:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:35 INFO - trying https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.tar.bz2 02:44:35 INFO - Downloading https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:44:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:44:36 INFO - Downloaded 62600457 bytes. 02:44:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.tar.bz2 02:44:36 INFO - mkdir: /builds/slave/test/properties 02:44:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:44:36 INFO - Writing to file /builds/slave/test/properties/build_url 02:44:36 INFO - Contents: 02:44:36 INFO - build_url:https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.tar.bz2 02:44:36 INFO - Running post-action listener: _resource_record_post_action 02:44:36 INFO - Running post-action listener: find_tests_for_verification 02:44:36 INFO - Running post-action listener: set_extra_try_arguments 02:44:36 INFO - [mozharness: 2017-09-02 09:44:36.967042Z] Finished download-and-extract step (success) 02:44:36 INFO - [mozharness: 2017-09-02 09:44:36.967139Z] Running populate-webroot step. 02:44:36 INFO - Running pre-action listener: _resource_record_pre_action 02:44:36 INFO - Running main action method: populate_webroot 02:44:36 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:44:36 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:44:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:44:36 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:44:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:44:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:36 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:36 INFO - 'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:44:36 INFO - 'tests': ['damp', 'tps']}, 02:44:36 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:36 INFO - 'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['damp', 'tps']}, 02:44:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:44:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:44:36 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:44:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:44:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:36 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:36 INFO - 'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:36 INFO - 'other-e10s': {'tests': ['a11yr', 02:44:36 INFO - 'ts_paint', 02:44:36 INFO - 'tpaint', 02:44:36 INFO - 'sessionrestore', 02:44:36 INFO - 'sessionrestore_many_windows', 02:44:36 INFO - 'sessionrestore_no_auto_restore', 02:44:36 INFO - 'tabpaint']}, 02:44:36 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['a11yr', 02:44:36 INFO - 'ts_paint', 02:44:36 INFO - 'tpaint', 02:44:36 INFO - 'sessionrestore', 02:44:36 INFO - 'sessionrestore_many_windows', 02:44:36 INFO - 'sessionrestore_no_auto_restore', 02:44:36 INFO - 'tabpaint']}, 02:44:36 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:44:36 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:44:36 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['bloom_basic_singleton']}, 02:44:36 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['bloom_basic']}, 02:44:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:44:36 INFO - 'tsvgr_opacity', 02:44:36 INFO - 'tart', 02:44:36 INFO - 'tscrollx', 02:44:36 INFO - 'tsvg_static']}, 02:44:36 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['tsvgx', 02:44:36 INFO - 'tsvgr_opacity', 02:44:36 INFO - 'tart', 02:44:36 INFO - 'tscrollx', 02:44:36 INFO - 'tsvg_static']}, 02:44:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:44:36 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:36 INFO - 'talos_options': ['--enable-stylo'], 02:44:36 INFO - 'tests': ['tp5o']}, 02:44:36 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:36 INFO - 'talos_options': ['--mitmproxy', 02:44:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:36 INFO - '--firstNonBlankPaint'], 02:44:36 INFO - 'tests': ['tp6_google', 02:44:36 INFO - 'tp6_youtube', 02:44:36 INFO - 'tp6_amazon', 02:44:36 INFO - 'tp6_facebook']}, 02:44:36 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:36 INFO - 'talos_options': ['--enable-stylo', 02:44:36 INFO - '--mitmproxy', 02:44:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:36 INFO - '--firstNonBlankPaint'], 02:44:36 INFO - 'tests': ['tp6_google', 02:44:36 INFO - 'tp6_youtube', 02:44:36 INFO - 'tp6_amazon', 02:44:36 INFO - 'tp6_facebook']}, 02:44:36 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:36 INFO - 'talos_options': ['--enable-stylo', 02:44:36 INFO - '--stylo-threads=1', 02:44:36 INFO - '--mitmproxy', 02:44:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:36 INFO - '--firstNonBlankPaint'], 02:44:36 INFO - 'tests': ['tp6_google', 02:44:36 INFO - 'tp6_youtube', 02:44:36 INFO - 'tp6_amazon', 02:44:36 INFO - 'tp6_facebook']}, 02:44:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:44:36 INFO - 'talos_options': ['--xperf_path', 02:44:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:36 INFO - 'tests': ['tp5n']}, 02:44:36 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:36 INFO - 'talos_options': ['--enable-stylo', 02:44:36 INFO - '--xperf_path', 02:44:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:36 INFO - 'tests': ['tp5n']}}} 02:44:36 INFO - Running post-action listener: _resource_record_post_action 02:44:36 INFO - [mozharness: 2017-09-02 09:44:36.981262Z] Finished populate-webroot step (success) 02:44:36 INFO - [mozharness: 2017-09-02 09:44:36.981350Z] Running create-virtualenv step. 02:44:36 INFO - Running pre-action listener: _resource_record_pre_action 02:44:36 INFO - Running main action method: create_virtualenv 02:44:36 INFO - Creating virtualenv /builds/slave/test/build/venv 02:44:36 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:44:36 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:44:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:44:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:44:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:37 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:37 INFO - Installing pip...............done. 02:44:37 INFO - Return code: 0 02:44:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:44:37 INFO - Reading from file tmpfile_stdout 02:44:37 INFO - Output received: 02:44:37 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:44:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, '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-1504345444.778711-834712415', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:44:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:44:37 INFO - Using env: {'DISPLAY': ':0', 02:44:37 INFO - 'HOME': '/home/cltbld', 02:44:37 INFO - 'LANG': 'en_US.UTF-8', 02:44:37 INFO - 'LANGUAGE': 'en_US:en', 02:44:37 INFO - 'LOGNAME': 'cltbld', 02:44:37 INFO - 'MAIL': '/var/mail/cltbld', 02:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:37 INFO - 'MOZ_NO_REMOTE': '1', 02:44:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:37 INFO - 'NO_EM_RESTART': '1', 02:44:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:37 INFO - 'PWD': '/builds/slave/test', 02:44:37 INFO - 'SHELL': '/bin/bash', 02:44:37 INFO - 'SHLVL': '1', 02:44:37 INFO - 'TERM': 'linux', 02:44:37 INFO - 'TMOUT': '86400', 02:44:37 INFO - 'USER': 'cltbld', 02:44:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:37 INFO - '_': '/tools/buildbot/bin/python'} 02:44:39 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:44:39 INFO - Downloading/unpacking pip>=1.5 02:44:39 INFO - Running setup.py egg_info for package pip 02:44:39 INFO - warning: no files found matching 'pip/cacert.pem' 02:44:39 INFO - warning: no files found matching '*.html' under directory 'docs' 02:44:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:44:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:44:39 INFO - Installing collected packages: pip 02:44:39 INFO - Found existing installation: pip 1.1 02:44:39 INFO - Uninstalling pip: 02:44:39 INFO - Successfully uninstalled pip 02:44:39 INFO - Running setup.py install for pip 02:44:39 INFO - warning: no files found matching 'pip/cacert.pem' 02:44:39 INFO - warning: no files found matching '*.html' under directory 'docs' 02:44:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:44:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:44:39 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Successfully installed pip 02:44:39 INFO - Cleaning up... 02:44:39 INFO - Return code: 0 02:44:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, '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-1504345444.778711-834712415', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:44:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:44:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:44:39 INFO - Using env: {'DISPLAY': ':0', 02:44:39 INFO - 'HOME': '/home/cltbld', 02:44:39 INFO - 'LANG': 'en_US.UTF-8', 02:44:39 INFO - 'LANGUAGE': 'en_US:en', 02:44:39 INFO - 'LOGNAME': 'cltbld', 02:44:39 INFO - 'MAIL': '/var/mail/cltbld', 02:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:39 INFO - 'MOZ_NO_REMOTE': '1', 02:44:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:39 INFO - 'NO_EM_RESTART': '1', 02:44:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:39 INFO - 'PWD': '/builds/slave/test', 02:44:39 INFO - 'SHELL': '/bin/bash', 02:44:39 INFO - 'SHLVL': '1', 02:44:39 INFO - 'TERM': 'linux', 02:44:39 INFO - 'TMOUT': '86400', 02:44:39 INFO - 'USER': 'cltbld', 02:44:39 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:39 INFO - '_': '/tools/buildbot/bin/python'} 02:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:39 INFO - Downloading/unpacking psutil>=3.1.1 02:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:44:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:40 INFO - Installing collected packages: psutil 02:44:40 INFO - Running setup.py install for psutil 02:44:40 INFO - building 'psutil._psutil_linux' extension 02:44:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:44:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:44:40 INFO - building 'psutil._psutil_posix' extension 02:44:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 02:44:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 02:44:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:41 INFO - Successfully installed psutil 02:44:41 INFO - Cleaning up... 02:44:41 INFO - Return code: 0 02:44:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, '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-1504345444.778711-834712415', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:44:41 INFO - Using env: {'DISPLAY': ':0', 02:44:41 INFO - 'HOME': '/home/cltbld', 02:44:41 INFO - 'LANG': 'en_US.UTF-8', 02:44:41 INFO - 'LANGUAGE': 'en_US:en', 02:44:41 INFO - 'LOGNAME': 'cltbld', 02:44:41 INFO - 'MAIL': '/var/mail/cltbld', 02:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:41 INFO - 'MOZ_NO_REMOTE': '1', 02:44:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:41 INFO - 'NO_EM_RESTART': '1', 02:44:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:41 INFO - 'PWD': '/builds/slave/test', 02:44:41 INFO - 'SHELL': '/bin/bash', 02:44:41 INFO - 'SHLVL': '1', 02:44:41 INFO - 'TERM': 'linux', 02:44:41 INFO - 'TMOUT': '86400', 02:44:41 INFO - 'USER': 'cltbld', 02:44:41 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:41 INFO - '_': '/tools/buildbot/bin/python'} 02:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:44:42 INFO - Installing collected packages: mozsystemmonitor 02:44:42 INFO - Running setup.py install for mozsystemmonitor 02:44:42 INFO - Successfully installed mozsystemmonitor 02:44:42 INFO - Cleaning up... 02:44:42 INFO - Return code: 0 02:44:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:42 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 0x171a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, '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-1504345444.778711-834712415', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:44:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:44:42 INFO - Using env: {'DISPLAY': ':0', 02:44:42 INFO - 'HOME': '/home/cltbld', 02:44:42 INFO - 'LANG': 'en_US.UTF-8', 02:44:42 INFO - 'LANGUAGE': 'en_US:en', 02:44:42 INFO - 'LOGNAME': 'cltbld', 02:44:42 INFO - 'MAIL': '/var/mail/cltbld', 02:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:42 INFO - 'MOZ_NO_REMOTE': '1', 02:44:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:42 INFO - 'NO_EM_RESTART': '1', 02:44:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:42 INFO - 'PWD': '/builds/slave/test', 02:44:42 INFO - 'SHELL': '/bin/bash', 02:44:42 INFO - 'SHLVL': '1', 02:44:42 INFO - 'TERM': 'linux', 02:44:42 INFO - 'TMOUT': '86400', 02:44:42 INFO - 'USER': 'cltbld', 02:44:42 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:42 INFO - '_': '/tools/buildbot/bin/python'} 02:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:42 INFO - Downloading/unpacking jsonschema==2.5.1 02:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:44:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:44 INFO - Downloading functools32-3.2.3-2.tar.gz 02:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:44:44 INFO - warning: no files found matching '*.txt' 02:44:44 INFO - no previously-included directories found matching 'build' 02:44:44 INFO - no previously-included directories found matching 'dist' 02:44:44 INFO - no previously-included directories found matching '.git*' 02:44:44 INFO - Installing collected packages: jsonschema, functools32 02:44:44 INFO - Running setup.py install for functools32 02:44:44 INFO - warning: no files found matching '*.txt' 02:44:44 INFO - no previously-included directories found matching 'build' 02:44:44 INFO - no previously-included directories found matching 'dist' 02:44:44 INFO - no previously-included directories found matching '.git*' 02:44:44 INFO - Successfully installed jsonschema functools32 02:44:44 INFO - Cleaning up... 02:44:44 INFO - Return code: 0 02:44:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x171a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, '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-1504345444.778711-834712415', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:44:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:44:44 INFO - Using env: {'DISPLAY': ':0', 02:44:44 INFO - 'HOME': '/home/cltbld', 02:44:44 INFO - 'LANG': 'en_US.UTF-8', 02:44:44 INFO - 'LANGUAGE': 'en_US:en', 02:44:44 INFO - 'LOGNAME': 'cltbld', 02:44:44 INFO - 'MAIL': '/var/mail/cltbld', 02:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:44 INFO - 'MOZ_NO_REMOTE': '1', 02:44:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:44 INFO - 'NO_EM_RESTART': '1', 02:44:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:44 INFO - 'PWD': '/builds/slave/test', 02:44:44 INFO - 'SHELL': '/bin/bash', 02:44:44 INFO - 'SHLVL': '1', 02:44:44 INFO - 'TERM': 'linux', 02:44:44 INFO - 'TMOUT': '86400', 02:44:44 INFO - 'USER': 'cltbld', 02:44:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:44 INFO - '_': '/tools/buildbot/bin/python'} 02:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:44:44 INFO - Cleaning up... 02:44:44 INFO - Return code: 0 02:44:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x171a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, '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-1504345444.778711-834712415', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:44:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:44:44 INFO - Using env: {'DISPLAY': ':0', 02:44:44 INFO - 'HOME': '/home/cltbld', 02:44:44 INFO - 'LANG': 'en_US.UTF-8', 02:44:44 INFO - 'LANGUAGE': 'en_US:en', 02:44:44 INFO - 'LOGNAME': 'cltbld', 02:44:44 INFO - 'MAIL': '/var/mail/cltbld', 02:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:44 INFO - 'MOZ_NO_REMOTE': '1', 02:44:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:44 INFO - 'NO_EM_RESTART': '1', 02:44:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:44 INFO - 'PWD': '/builds/slave/test', 02:44:44 INFO - 'SHELL': '/bin/bash', 02:44:44 INFO - 'SHLVL': '1', 02:44:44 INFO - 'TERM': 'linux', 02:44:44 INFO - 'TMOUT': '86400', 02:44:44 INFO - 'USER': 'cltbld', 02:44:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:44 INFO - '_': '/tools/buildbot/bin/python'} 02:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:44 INFO - Downloading/unpacking blobuploader==1.2.4 02:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:45 INFO - Downloading blobuploader-1.2.4.tar.gz 02:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:44:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:44:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:46 INFO - Downloading docopt-0.6.1.tar.gz 02:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:44:46 INFO - Installing collected packages: blobuploader, requests, docopt 02:44:46 INFO - Running setup.py install for blobuploader 02:44:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:44:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:44:46 INFO - Running setup.py install for requests 02:44:46 INFO - Running setup.py install for docopt 02:44:46 INFO - Successfully installed blobuploader requests docopt 02:44:46 INFO - Cleaning up... 02:44:47 INFO - Return code: 0 02:44:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:47 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 0x171a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, '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-1504345444.778711-834712415', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:44:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:44:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:44:47 INFO - Using env: {'DISPLAY': ':0', 02:44:47 INFO - 'HOME': '/home/cltbld', 02:44:47 INFO - 'LANG': 'en_US.UTF-8', 02:44:47 INFO - 'LANGUAGE': 'en_US:en', 02:44:47 INFO - 'LOGNAME': 'cltbld', 02:44:47 INFO - 'MAIL': '/var/mail/cltbld', 02:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:47 INFO - 'MOZ_NO_REMOTE': '1', 02:44:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:47 INFO - 'NO_EM_RESTART': '1', 02:44:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:47 INFO - 'PWD': '/builds/slave/test', 02:44:47 INFO - 'SHELL': '/bin/bash', 02:44:47 INFO - 'SHLVL': '1', 02:44:47 INFO - 'TERM': 'linux', 02:44:47 INFO - 'TMOUT': '86400', 02:44:47 INFO - 'USER': 'cltbld', 02:44:47 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:47 INFO - '_': '/tools/buildbot/bin/python'} 02:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:47 INFO - Running setup.py (path:/tmp/pip-RYcPc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:47 INFO - Running setup.py (path:/tmp/pip-BZUxTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:47 INFO - Running setup.py (path:/tmp/pip-Egq9Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:47 INFO - Running setup.py (path:/tmp/pip-BQiRhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:47 INFO - Running setup.py (path:/tmp/pip-AggjZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:47 INFO - Running setup.py (path:/tmp/pip-Jql_ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:47 INFO - Running setup.py (path:/tmp/pip-wotbz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:47 INFO - Running setup.py (path:/tmp/pip-IvFtm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:47 INFO - Running setup.py (path:/tmp/pip-VFQmmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:47 INFO - Running setup.py (path:/tmp/pip-YU1AWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:47 INFO - Running setup.py (path:/tmp/pip-VyOSXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:48 INFO - Running setup.py (path:/tmp/pip-aManWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:48 INFO - Running setup.py (path:/tmp/pip-1rNrxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:48 INFO - Running setup.py (path:/tmp/pip-ptrLb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:48 INFO - Running setup.py (path:/tmp/pip-oWoKP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:48 INFO - Running setup.py (path:/tmp/pip-6nkLSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:48 INFO - Running setup.py (path:/tmp/pip-ieVAb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:44:48 INFO - Running setup.py install for manifestparser 02:44:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:44:48 INFO - Running setup.py install for mozcrash 02:44:48 INFO - Running setup.py install for mozdebug 02:44:48 INFO - Running setup.py install for mozdevice 02:44:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:44:48 INFO - Running setup.py install for mozfile 02:44:48 INFO - Running setup.py install for mozhttpd 02:44:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for mozinfo 02:44:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for mozInstall 02:44:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for mozleak 02:44:49 INFO - Running setup.py install for mozlog 02:44:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for moznetwork 02:44:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for mozprocess 02:44:49 INFO - Running setup.py install for mozprofile 02:44:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for mozrunner 02:44:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:44:49 INFO - Running setup.py install for mozscreenshot 02:44:49 INFO - Running setup.py install for moztest 02:44:50 INFO - Running setup.py install for mozversion 02:44:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:44:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:44:50 INFO - Cleaning up... 02:44:50 INFO - Return code: 0 02:44:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:50 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 0x171a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, '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-1504345444.778711-834712415', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:44:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:44:50 INFO - Using env: {'DISPLAY': ':0', 02:44:50 INFO - 'HOME': '/home/cltbld', 02:44:50 INFO - 'LANG': 'en_US.UTF-8', 02:44:50 INFO - 'LANGUAGE': 'en_US:en', 02:44:50 INFO - 'LOGNAME': 'cltbld', 02:44:50 INFO - 'MAIL': '/var/mail/cltbld', 02:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:50 INFO - 'MOZ_NO_REMOTE': '1', 02:44:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:50 INFO - 'NO_EM_RESTART': '1', 02:44:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:50 INFO - 'PWD': '/builds/slave/test', 02:44:50 INFO - 'SHELL': '/bin/bash', 02:44:50 INFO - 'SHLVL': '1', 02:44:50 INFO - 'TERM': 'linux', 02:44:50 INFO - 'TMOUT': '86400', 02:44:50 INFO - 'USER': 'cltbld', 02:44:50 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:50 INFO - '_': '/tools/buildbot/bin/python'} 02:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:50 INFO - Running setup.py (path:/tmp/pip-g9Rqsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:50 INFO - Running setup.py (path:/tmp/pip-SU2Ndl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:50 INFO - Running setup.py (path:/tmp/pip-KT17ND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:50 INFO - Running setup.py (path:/tmp/pip-mwdHID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:50 INFO - Running setup.py (path:/tmp/pip-BAWD6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:50 INFO - Running setup.py (path:/tmp/pip-8VBSGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:50 INFO - Running setup.py (path:/tmp/pip-saXznK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:50 INFO - Running setup.py (path:/tmp/pip-GVl17R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:50 INFO - Running setup.py (path:/tmp/pip-ya0EjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:51 INFO - Running setup.py (path:/tmp/pip-artQNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:51 INFO - Running setup.py (path:/tmp/pip-BiWwJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:51 INFO - Running setup.py (path:/tmp/pip-cgPPW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:51 INFO - Running setup.py (path:/tmp/pip-okNuxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:51 INFO - Running setup.py (path:/tmp/pip-AwJEzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:51 INFO - Running setup.py (path:/tmp/pip-_gKfTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:51 INFO - Running setup.py (path:/tmp/pip-Zzgsfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:51 INFO - Running setup.py (path:/tmp/pip-2I9Evr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:44:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:52 INFO - Downloading blessings-1.6.tar.gz 02:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:44:52 INFO - Installing collected packages: blessings 02:44:52 INFO - Running setup.py install for blessings 02:44:52 INFO - Successfully installed blessings 02:44:52 INFO - Cleaning up... 02:44:52 INFO - Return code: 0 02:44:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:44:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:53 INFO - Reading from file tmpfile_stdout 02:44:53 INFO - Current package versions: 02:44:53 INFO - argparse == 1.2.1 02:44:53 INFO - blessings == 1.6 02:44:53 INFO - blobuploader == 1.2.4 02:44:53 INFO - docopt == 0.6.1 02:44:53 INFO - functools32 == 3.2.3-2 02:44:53 INFO - jsonschema == 2.5.1 02:44:53 INFO - manifestparser == 1.1 02:44:53 INFO - mozInstall == 1.13 02:44:53 INFO - mozcrash == 1.0 02:44:53 INFO - mozdebug == 0.1 02:44:53 INFO - mozdevice == 0.51 02:44:53 INFO - mozfile == 1.2 02:44:53 INFO - mozhttpd == 0.7 02:44:53 INFO - mozinfo == 0.10 02:44:53 INFO - mozleak == 0.1 02:44:53 INFO - mozlog == 3.5 02:44:53 INFO - moznetwork == 0.27 02:44:53 INFO - mozprocess == 0.25 02:44:53 INFO - mozprofile == 0.28 02:44:53 INFO - mozrunner == 6.13 02:44:53 INFO - mozscreenshot == 0.1 02:44:53 INFO - mozsystemmonitor == 0.3 02:44:53 INFO - moztest == 0.8 02:44:53 INFO - mozversion == 1.4 02:44:53 INFO - psutil == 3.1.1 02:44:53 INFO - requests == 1.2.3 02:44:53 INFO - wsgiref == 0.1.2 02:44:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44: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')]} 02:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44: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')]} 02:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:53 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 0x171a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, '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-1504345444.778711-834712415', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:44:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:44:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:44:53 INFO - Using env: {'DISPLAY': ':0', 02:44:53 INFO - 'HOME': '/home/cltbld', 02:44:53 INFO - 'LANG': 'en_US.UTF-8', 02:44:53 INFO - 'LANGUAGE': 'en_US:en', 02:44:53 INFO - 'LOGNAME': 'cltbld', 02:44:53 INFO - 'MAIL': '/var/mail/cltbld', 02:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:53 INFO - 'MOZ_NO_REMOTE': '1', 02:44:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:53 INFO - 'NO_EM_RESTART': '1', 02:44:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:53 INFO - 'PWD': '/builds/slave/test', 02:44:53 INFO - 'SHELL': '/bin/bash', 02:44:53 INFO - 'SHLVL': '1', 02:44:53 INFO - 'TERM': 'linux', 02:44:53 INFO - 'TMOUT': '86400', 02:44:53 INFO - 'USER': 'cltbld', 02:44:53 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:53 INFO - '_': '/tools/buildbot/bin/python'} 02:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:44:53 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:44:54 INFO - Installing collected packages: simplejson 02:44:54 INFO - Running setup.py install for simplejson 02:44:54 INFO - building 'simplejson._speedups' extension 02:44:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 02:44:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 02:44:55 INFO - Successfully installed simplejson 02:44:55 INFO - Cleaning up... 02:44:55 INFO - Return code: 0 02:44:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:44: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')]} 02:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44: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')]} 02:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a86a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, '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-1504345444.778711-834712415', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:44: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', 'jsonschema'] in /builds/slave/test/build 02:44: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 jsonschema 02:44:55 INFO - Using env: {'DISPLAY': ':0', 02:44:55 INFO - 'HOME': '/home/cltbld', 02:44:55 INFO - 'LANG': 'en_US.UTF-8', 02:44:55 INFO - 'LANGUAGE': 'en_US:en', 02:44:55 INFO - 'LOGNAME': 'cltbld', 02:44:55 INFO - 'MAIL': '/var/mail/cltbld', 02:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:55 INFO - 'MOZ_NO_REMOTE': '1', 02:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:55 INFO - 'NO_EM_RESTART': '1', 02:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:55 INFO - 'PWD': '/builds/slave/test', 02:44:55 INFO - 'SHELL': '/bin/bash', 02:44:55 INFO - 'SHLVL': '1', 02:44:55 INFO - 'TERM': 'linux', 02:44:55 INFO - 'TMOUT': '86400', 02:44:55 INFO - 'USER': 'cltbld', 02:44:55 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:55 INFO - '_': '/tools/buildbot/bin/python'} 02:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:44:55 INFO - Cleaning up... 02:44:55 INFO - Return code: 0 02:44:55 INFO - Running post-action listener: _resource_record_post_action 02:44:55 INFO - Running post-action listener: _start_resource_monitoring 02:44:55 INFO - Starting resource monitoring. 02:44:55 INFO - [mozharness: 2017-09-02 09:44:55.870693Z] Finished create-virtualenv step (success) 02:44:55 INFO - [mozharness: 2017-09-02 09:44:55.871202Z] Running install step. 02:44:55 INFO - Running pre-action listener: _resource_record_pre_action 02:44:55 INFO - Running main action method: install 02:44:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:56 INFO - Reading from file tmpfile_stdout 02:44:56 INFO - Detecting whether we're running mozinstall >=1.0... 02:44:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:44:56 INFO - Reading from file tmpfile_stdout 02:44:56 INFO - Output received: 02:44:56 INFO - Usage: mozinstall [options] installer 02:44:56 INFO - Options: 02:44:56 INFO - -h, --help show this help message and exit 02:44:56 INFO - -d DEST, --destination=DEST 02:44:56 INFO - Directory to install application into. [default: 02:44:56 INFO - "/builds/slave/test"] 02:44:56 INFO - --app=APP Application being installed. [default: firefox] 02:44:56 INFO - mkdir: /builds/slave/test/build/application 02:44:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:45:12 INFO - Reading from file tmpfile_stdout 02:45:12 INFO - Output received: 02:45:12 INFO - /builds/slave/test/build/application/firefox/firefox 02:45:12 INFO - Running post-action listener: _resource_record_post_action 02:45:12 INFO - [mozharness: 2017-09-02 09:45:12.739273Z] Finished install step (success) 02:45:12 INFO - [mozharness: 2017-09-02 09:45:12.739522Z] Running setup-mitmproxy step. 02:45:12 INFO - Running pre-action listener: _resource_record_pre_action 02:45:12 INFO - Running main action method: setup_mitmproxy 02:45:12 INFO - Skipping: mitmproxy is not required 02:45:12 INFO - Running post-action listener: _resource_record_post_action 02:45:12 INFO - [mozharness: 2017-09-02 09:45:12.740630Z] Finished setup-mitmproxy step (success) 02:45:12 INFO - [mozharness: 2017-09-02 09:45:12.740845Z] Running run-tests step. 02:45:12 INFO - Running pre-action listener: _resource_record_pre_action 02:45:12 INFO - Running pre-action listener: _set_gcov_prefix 02:45:12 INFO - Running main action method: run_tests 02:45:12 WARNING - Try message not found. 02:45:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:12 INFO - Python 2.7.3 02:45:12 INFO - Return code: 0 02:45:12 INFO - grabbing minidump binary from tooltool 02:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:12 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 0x1a8a090>, '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 0x1a8ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a84f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:12 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 02:45:12 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 02:45:12 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 02:45:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:12 INFO - Return code: 0 02:45:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:45:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:12 INFO - ENV: RUST_BACKTRACE is now full 02:45:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:45:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/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 02:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/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 02:45:12 INFO - Using env: {'DISPLAY': ':0', 02:45:12 INFO - 'HOME': '/home/cltbld', 02:45:12 INFO - 'LANG': 'en_US.UTF-8', 02:45:12 INFO - 'LANGUAGE': 'en_US:en', 02:45:12 INFO - 'LOGNAME': 'cltbld', 02:45:12 INFO - 'MAIL': '/var/mail/cltbld', 02:45:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:12 INFO - 'MOZ_NO_REMOTE': '1', 02:45:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:12 INFO - 'NO_EM_RESTART': '1', 02:45:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:12 INFO - 'PWD': '/builds/slave/test', 02:45:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:12 INFO - 'RUST_BACKTRACE': 'full', 02:45:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:12 INFO - 'SHELL': '/bin/bash', 02:45:12 INFO - 'SHLVL': '1', 02:45:12 INFO - 'TERM': 'linux', 02:45:12 INFO - 'TMOUT': '86400', 02:45:12 INFO - 'USER': 'cltbld', 02:45:12 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:12 INFO - '_': '/tools/buildbot/bin/python'} 02:45:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/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 02:45:13 INFO - mozversion application_buildid: 20170902085228 02:45:13 INFO - mozversion application_changeset: b01a7e57425b5fe791ab091f5c33e069890753fb 02:45:13 INFO - mozversion application_display_name: Nightly 02:45:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:13 INFO - mozversion application_name: Firefox 02:45:13 INFO - mozversion application_remotingname: firefox 02:45:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:45:13 INFO - mozversion application_vendor: Mozilla 02:45:13 INFO - mozversion application_version: 57.0a1 02:45:13 INFO - mozversion platform_buildid: 20170902085228 02:45:13 INFO - mozversion platform_changeset: b01a7e57425b5fe791ab091f5c33e069890753fb 02:45:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:45:13 INFO - mozversion platform_version: 57.0a1 02:45:13 INFO - using testdate: 1504345513 02:45:13 INFO - actual date: 1504345513 02:45:13 INFO - starting webserver on 'localhost:43480' 02:45:13 INFO - SUITE-START | Running 2 tests 02:45:13 INFO - TEST-START | dromaeo_css 02:45:13 INFO - Initialising browser for dromaeo_css test... 02:45:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43480/getInfo.html -profile /tmp/tmp9Nei5Z/profile 02:45:13 INFO - TEST-INFO | started process 30594 (/builds/slave/test/build/application/firefox/firefox http://localhost:43480/getInfo.html) 02:45:18 INFO - TEST-INFO | 30594: exit 0 02:45:18 INFO - Browser initialized. 02:45:18 INFO - Running cycle 1/1 for dromaeo_css test... 02:45:18 INFO - Using env: {'DISPLAY': ':0', 02:45:18 INFO - 'HOME': '/home/cltbld', 02:45:18 INFO - 'JSGC_DISABLE_POISONING': '1', 02:45:18 INFO - 'LANG': 'en_US.UTF-8', 02:45:18 INFO - 'LANGUAGE': 'en_US:en', 02:45:18 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:45:18 INFO - 'LOGNAME': 'cltbld', 02:45:18 INFO - 'MAIL': '/var/mail/cltbld', 02:45:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:18 INFO - 'MOZ_CRASHREPORTER': '1', 02:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:18 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:45:18 INFO - 'MOZ_NO_REMOTE': '1', 02:45:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:18 INFO - 'NO_EM_RESTART': '1', 02:45:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:18 INFO - 'PWD': '/builds/slave/test', 02:45:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:18 INFO - 'RUST_BACKTRACE': 'full', 02:45:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:18 INFO - 'SHELL': '/bin/bash', 02:45:18 INFO - 'SHLVL': '1', 02:45:18 INFO - 'TERM': 'linux', 02:45:18 INFO - 'TMOUT': '86400', 02:45:18 INFO - 'USER': 'cltbld', 02:45:18 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 02:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:18 INFO - '_': '/tools/buildbot/bin/python'} 02:45:18 INFO - TEST-INFO | started process 30777 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Nei5Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:19 INFO - PID 30777 | 02:45:19 INFO - PID 30777 | (/builds/slave/test/build/application/firefox/firefox:30829): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:45:19 INFO - PID 30777 | 02:45:30 INFO - PID 30777 | RSS: Main: 169639936 02:45:30 INFO - PID 30777 | 02:48:28 INFO - PID 30777 | Cycle 1(1): loaded http://localhost:43480/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43480/tests/dromaeo/cssquery-ext.html) 02:48:30 INFO - PID 30777 | RSS: Main: 172027904 02:48:30 INFO - PID 30777 | 02:51:31 INFO - PID 30777 | Cycle 1(1): loaded http://localhost:43480/tests/dromaeo/cssquery-ext.html (next: http://localhost:43480/tests/dromaeo/cssquery-jquery.html) 02:51:32 INFO - PID 30777 | RSS: Main: 166621184 02:51:32 INFO - PID 30777 | 02:54:34 INFO - PID 30777 | Cycle 1(1): loaded http://localhost:43480/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43480/tests/dromaeo/cssquery-mootools.html) 02:54:34 INFO - PID 30777 | RSS: Main: 170078208 02:54:34 INFO - PID 30777 | 02:57:36 INFO - PID 30777 | Cycle 1(1): loaded http://localhost:43480/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43480/tests/dromaeo/cssquery-prototype.html) 02:57:37 INFO - PID 30777 | RSS: Main: 168910848 02:57:37 INFO - PID 30777 | 03:00:40 INFO - PID 30777 | Cycle 1(1): loaded http://localhost:43480/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43480/tests/dromaeo/cssquery-yui.html) 03:00:41 INFO - PID 30777 | RSS: Main: 169975808 03:00:41 INFO - PID 30777 | 03:03:43 INFO - PID 30777 | Cycle 1(1): loaded http://localhost:43480/tests/dromaeo/cssquery-yui.html (next: http://localhost:43480/tests/dromaeo/cssquery-dojo.html) 03:03:43 INFO - PID 30777 | RSS: Main: 172412928 03:03:43 INFO - PID 30777 | 03:03:43 INFO - PID 30777 | __start_tp_report 03:03:43 INFO - PID 30777 | _x_x_mozilla_page_load 03:03:43 INFO - PID 30777 | _x_x_mozilla_page_load_details 03:03:43 INFO - PID 30777 | |i|pagename|runs| 03:03:43 INFO - PID 30777 | |0;dojo.html;3301.521279414485;3309.659587866914;3346.7322614190866;3366.2257680733424;3380.5943286805586;6294.087357333184;6364.109024736535;6443.548951573392;6490.707918143683;6539.542232043759;6312.9368706313035;6433.903491447632;6434.099226108348;6454.774082907099;6478.74085036598;5858.941410585893;5955.047192449209;5971.790987315445;5995.820125396245;6035.909461358061;5903.380145084769;5967.582269241155;5993.550483713717;6001;6011.969940150315;5816.796412125576;5956.970215148918;5961.225040744697;6007.4893634041155;6029.969850150765;5736.541076713853;5919.49684276837;5953.3451320354725;5967.313758917737;6016.308124565688;5777.508911742507;5826.446487583673;5881;5882.6764527950945;5898.144769008512;6424.74301027963;6434.324395938406;6438.484921206292;6458.773942912023;6467.902981455282;6824.6928888200155;6841.144856892888;6862.073620061277;6914.723411063552;6920.550164239308;6909.170899492043;6910.654467276667;6989.720411183546;7005.334493223136;7038.718451261943;6339;6418.743250269984;6422.518311126684;6425.036244563353;6425.1647285853005;6956.582605043714;7020.262872398427;7053.4004609608755;7063.646817659199;7068.363847253669;6726.932730672631;6727.058211850345;6814.046033555306;6823;6823.863522729517;6953.165620125617;6979.6859141337745;6982.057422248033;6989.33601307875;6989.580625162506;6856.485763567753;6857.55425897315;6858.211305699809;6945.131858517685;6952.617606031616;6770.593764374154;6814.318568143245;6816.863662726718;6818.829529261808;6871.759488417982;5430.83707488776;5444.972775136178;5446.809361672323;5448.972755136199;5449.4005659377435;4343.5439278875265;4706.529347065266;4711.528847115261;4771.928421073687;4773.355596994361;6019.157317975487;6048.092786082035;6076.8784624308155;6085.939140608537;6086.60437071589;6924.549904256207;6956.165260168812;7019.92980070203;7029.05107810439;7047.823804404931;5960.612560183574;5989.610675306091;6049.304330002018;6073.0283154695035;6088.665123418255;5770.047942089592;5850.853728656818;5863.9738045841805;5865.20819689345;5885.852853678607;6621.039949207338;6625.105610742585;6659.167604049494;6660.6336651484635;6697.966510167418;6844.247132815386;6919.515633905779;6997.230304666381;7049.118860142482;7049.682764275723;6678.699458524281;6693.163354580677;6706.9999999998045;6752.054712340375;6771.322867713387;6849.383555480031;6868.759593414206;6915.896261556181;6927.7228910845015;6950.582965022115;6815.079964204769;6921.584704917721;6925.342092501204;6934.757283495053;6935.202451718117;6556.63938483388;6643.634600097041;6665.533412661067;6669.199696036507;6672.933270667231;6697.330266973264;6727.495437842279;6729.831754206184;6741.056252124805;6748.763793267212;6687.398134167948;6688.899666504909;6717.261101278757;6717.529772915849;6718.462522998246;6719.361660642231;6780.72877084911;6842.794716158523;6844.315568443315;6847.281035491202;6538.346165383424;6547.70535325902;6630.83422914431;6631.237407697983;6635.23694775107 03:03:43 INFO - PID 30777 | |1;ext.html;757.5909009135067;840.3067469337796;868.7654333330001;870.438567124205;1123.3821398230982;29959.85020074899;30683.539746903814;39675.404868926955;40439.393409098884;40444.79777601115;41289.58710412902;41736.58263417365;41764.16471670565;41798.79100604493;41809.163816723645;7068.646567671622;7234.457415693818;7417.888731669016;7543.69825206991;7545.622718864062;7474.065741782278;7574.9242507575045;7577;7579.052618422697;7581.620918954058;7537.057867766529;7589.848203035936;7593.05086864142;7597.126330471983;7598;7511.06111736033;7519.323260906517;7561.24387561245;7561.697532098709;7564.357029652486;7537.9623101884135;7554.244575542401;7574.3183113520045;7577.886331705029;7579.1662917079075;36727.63272367269;36795;36891.26217475635;36905.630943690754;36950.81524592387;3470.35798377301;3473.7221022318154;3490.5636795400574;3497.7726447780815;3502.124468882779;30085.849570752005;30313.848430757706;30335.089947301618;30476.54285185724;30516.931907383358;4961.231009193581;5194.532492075731;5208;5229.607779416521;5232.843014709565;188995;190626;191119;191254.04372978047;191543;46534.069318614114;46591.30113048307;46623.53376466192;46738.298925516145;46796.5320346799;51033.74483127561;51848.22227666588;51907.22139167915;51959;52031.47968520342;15733.527994160193;15916.76124858128;16119.758203626954;16149;16151.434699785686;11124.443777811239;11285.21003529745;12915.16051456671;13065;13172.209667419986;14178.71642567143;14179.007469477037;14331.426742930165;14475.348609312607;14531.418743250151;5724.25584673998;5758.309002919593;5781.682007489629;5830.271216097988;5923.822285331447;989.9704307520242;995.621663767778;1000.8398656214971;1016.8373060310315;1033.6537260017828;21501.78498215029;22950.540989180125;28460.71539284622;28692.856535717186;28952.565711514344;1093.814051611212;1102.5148934468807;1104;1106.9280496767685;1109.9611513597147;12184.08619353552;15525.844741552666;15603.531894043197;15618.062916225063;15792;222429;225306;225598;225611;225631;53773.731131344095;54295.45704542983;54545.45454545483;54618;54932;1430.2777097565663;1461.3058797071305;1469.7281003014477;1477.1506383829126;1487.9256037198313;26357.60463593086;26655.067072652364;26952.86523567448;27038.45923081488;27109.72890271072;37365.252694945404;37660.811695942444;37790.43314350342;37803;37939;202694;204407;204920;206595;206960;33985.490217647246;34315.485267720505;34319.31361372808;36164.27671446609;36179.27641447209;44386.334204987594;44470;44483;44767.776161118985;44783.77608111939;32340.676593234708;32724.345513089127;32852.342953141284;33071.66928330782;33185;31992.520112198803;32285.677143228266;32302;32492;32855.17862053468;36393.45409818802;36413.817930910176;36426.81786591156;37053.81473092725;37090;31398.372032559677;31894;32099.518507222878;32179.678203218606;32441.837790810896;23351.299461016195;23845.52308953846;23865.403364916016;23967.52064958726;24439.511209775348 03:03:43 INFO - PID 30777 | |2;jquery.html;561.1582626060908;561.1946856261263;566.9631473954195;570.840164753869;576.3343338444097;21175.68236476452;22126.22558210463;22259.66610500842;22267.554648907033;22369.440763980907;23529.764702353015;25861.74138258617;25982.870085649545;26079.60880586788;26158.738412615916;6424.550281480287;6614.107095542088;6614.669266536678;6615.140031795884;6615.5369124161325;6428.839279018039;6510.5442619016685;6575.671216439159;6670.433013193884;6710.228323742784;6658.933410665904;6668.166479190101;6669.066330713704;6673.399394054559;6674.899876501856;6671.566348187353;6677.632730199837;6685.164354455717;6685.866282674319;6689.966550167218;6195.380461953769;6204.689765511706;6206.317305096436;6211.658358790265;6214.316425193225;28199.85900070507;29002.854985725146;29341.119766407042;29673.25816854596;29981.25046873824;2044.8159665630017;2048.621005114059;2060.093558834123;2071.1301253473575;2086.54096098858;19459.318923837596;20088;20101.095450704754;20104.09531571068;20105.195792168295;2383.6928304857556;2416.5408572371193;2460.1266550374717;2478.2069737683946;2485.701715794098;135941.3202933989;139857;140391;140594;140981;13984.580462586138;14342.282885855666;14349.282535873164;14898.925505372621;15172.544823655307;22285.220017299318;23141.19005834788;23572.7642723574;23584.528309433714;23606.285277193343;9637.855432168522;9943.602255909682;9999.750006249902;10015.749606259757;10018.39889606626;9074.86387704185;9170.761947137185;9210.171084602418;9212.447253164832;9248.16766491019;9197.516670499062;9215.986241513428;9323.953380233055;9337.253019758404;9564.713058608253;2571.781398581104;2573.343797331706;2594.5718956372366;2596.818222724391;2746.7115952824665;947.4552132523827;951.6859436385976;993.8707967964118;1012.7974405118858;1021.2748948246827;19490.610187796166;19792.802071979382;19859.91470895535;20139.79860201379;20613.690794638092;1021.7343490692474;1051.9053285204368;1052.867942416597;1070.0476575847501;1074.6292529077455;10567.577296908192;10884.238103332691;10899.073578745736;11013.07271227532;11082.778344433065;473979;480844;487453;487527;489884;114004.42997784958;117920;119649;120147;120238.59522809494;1179.0508640544272;1213.8239955206445;1214.4899142360052;1219.6402061391912;1228.8095345221504;16857.91571042186;16985.23566439538;17282.308707651555;17400.73898891543;17560.209790559198;50776.74611626918;51642.483575163766;52013.219801702246;53582.196267055246;54345.72827135839;69896.30103699102;70241.64879175776;70433.64783176256;70854.64572677104;70860.29139708537;14367.856321436651;14549.345279462237;14619.488317908823;14655.26723663834;14683.19242441676;22354.888225558767;22736.545269094193;22813.087476500754;22976.54046919019;23043.539129216988;18080.90959545194;18192.636147276717;18233.452996410128;18473.81526184721;18576.907115464335;16596.170191490135;16726.58183545421;16779.664406711552;17075.31698732037;17352.82647173512;18640.533986650444;18686.34597789071;18810.905945470186;18940.905295473436;18950.052497375353;14298.928505357406;14305.499307524187;14360.640983975483;14460.27698615086;14492.637684057561;17878.821211787716;18132.818671813115;18220.18009189616;18313.908430457763;18371.724424133918 03:03:43 INFO - PID 30777 | |3;mootools.html;1307.2548647271055;1361.278522383136;1367.835859696836;1371.4308561946793;1372.5470594703745;14691.706165876689;15303.464378746747;15309;15327.080375177484;15335.539933801976;14736.189509576969;15654.686906261868;15733.449329273477;15749.055056696578;15766.684666306666;2728.577070554062;2771.9168424947284;2778.2220978126156;2780.4995100881833;2784.3456787654886;2732.931676708078;2842.3036356092784;2848.416074704691;2848.7151284871557;2849.244950088228;2721.9591706124324;2722.4555088982183;2751.697313295536;2757.352022274774;2807.143821134553;2682.7183145769704;2713.02331160781;2737.083077169151;2738.7535121838932;2750.0787236275874;2671.9599206011926;2674.946501069968;2682.8122031457806;2687.7177896320995;2698.1905428371606;14825.48110816127;15000.774988375184;15144.848551514564;15150.469733559383;15175.468858589897;7764.689412423496;8075.192480751936;8113.229243221885;8115.8782618260775;8118.228768267005;5282.815101471469;5432.538234250093;5511.862203444906;5527.088030474967;5571.554275657938;1946.7858535561077;1950.1224448997866;1951.0829909942304;1992.9601407971904;1996.2314508914028;23753.524929501313;24238.151664691828;26297.73702262972;26773.73226267732;26872.462550748874;6944.826379340557;7038.92961070393;7070.575765454089;7100.254473280231;7133.251008644018;17127;17182.484525464257;17299.13504324804;17300.48098557045;17328.30686772515;7846.725364612325;7964.044314682275;7994.960025199953;8005.519668819773;8046.597670116471;7389.1502477214735;7392.112946446461;7394.186639469654;7419.554826710415;7434.851302973911;5222.1383471727495;5400.702961337164;5406.810761623325;5418.431064738224;5456.727163641802;1611.7662938873798;1619.9919000404923;1630.9755353669705;1631.1191956343441;1664.5672125247281;861.3625916821633;880.4101252160939;890.2210565754964;896.2650626486349;906.5648488725317;7339.669714862844;7403.962980185064;7416.295451932057;7422.48042637021;7581.810454738676;657.9736810527621;660.1154453032959;664.4153145232165;672.0926748889059;672.8351553869236;4991.550760431579;5137.434882162959;5173.611979101583;5214.087534681492;5316.229146773773;21263.36209913705;21603.783962160178;22036.33890983273;22123.557528849335;22415;21506;21557.35327940133;21720.348389548024;21966.231181908555;21994.120235190734;9727.41635501858;10030.247731420026;10836.67489975302;11020.283681560673;11112.944435277772;8528.14718528167;8534.871976920474;8544.444611100258;8572.442791218551;8601.741947741577;18694.626107478045;18910.243590256785;19102;19146;19545.12046957862;19122.139503722643;19158.904205478884;19465.124069417194;19569.60860782748;19740.111694973475;11581.594644187413;11811.173217874666;11839;11843.644690659294;11851.288922664668;13084.607461776162;13676.863231367559;14144.151350918977;14214.786778198128;14219.431222750974;10750.462476875968;10805.621803237163;10863.022327990519;10921.344719316867;11146.108311335236;10971.83542246851;11006;11033.172512061466;11259.268147570381;11301.491432885705;9139.45163290178;9356.251499879858;9449.244060475008;9466.100720431548;9520.90479095228;6679.298673639198;6741.22475915276;6773.695183716646;6838.965805170942;6879;6748.460123190232;6861.4510839133745;6876.484263680344;6991.405730512964;7027.297270272932 03:03:43 INFO - PID 30777 | |4;prototype.html;511.1106674386566;512.4337606944329;523.960702947279;531.3464438740349;537.9650322729022;6451.838704032401;6472.644004579746;6529.314421985694;6563.376479234471;6565.227388605073;6290.622562646245;6308.886523014784;6346.333634968331;6395.488360931126;6399.328070552595;5240.737963101839;5291.523762861341;5297.602679799012;5319.867003324909;5327;4852.902941941159;5199.14214154664;5223.895522089556;5312.229726689628;5337.599680023995;5002.324686167357;5015.5736762375245;5034.5468907798295;5038.571721403685;5079.390473143228;4977.626677999128;5028.823991160292;5034.974825125888;5041.445441001487;5060.493950604947;4512.368268442421;4530.456345238559;4590.311453282001;4818.7831547580445;4933.7286449245275;6268.717907694165;6281.277653069909;6314.610785627155;6330.68346582669;6331.746730130789;6046.107437521229;6138.785142520035;6155.661438620874;6157.384261573851;6178.876422471525;6763.594184348905;6782.898256526156;6822.351876571718;6869.7595584154815;6885.798103881719;5382.407935127133;5424.755885985101;5445.346558413015;5446.754896029688;5468.644538105011;6783.6269005204695;6887.586744795329;6943.479239057091;7013.579185248901;7212.495125341228;5509.834704958857;5569.136783798518;5605.5795815313195;5622.7750889963945;5640.915386269209;5645.5483561314995;5669.376368599451;5685.6304340218585;5696.11710184922;5754.625949313281;6093.451589356979;6111.419415155642;6127.877442451126;6149.2620885494025;6191.690415479208;6781.355771201728;6858.622775747382;6889.827754306182;6895.827604309832;6917.204521479994;1956.5402130499322;1988.4730546405215;1995.8303544198616;2000.5898790747863;2012.8188463038398;1340.7117469744053;1440.6686462113562;1475.3803402570945;1481.2371628611274;1491.597268737435;5857.916285487198;5864.237649105674;5868.882622347614;5886.911696324559;6011.677430965181;6763.458923286125;6891.27641597625;6915.792526224222;6927.549709268881;6964.129483814523;5766.4142360850265;5832.825015249549;5840.649561026352;5892.5285977122585;5906;3612.909677258037;3631.1662610381586;3639.79981101042;3642.6357364263363;3645.289168612095;6616.206055273398;6656.633885136364;6661.4004739573675;6700.329967003261;6715.294894036154;6951.270116637781;7020.192677842011;7031.964840175767;7077.5137221183095;7117.145942486831;6661.866762664719;6690.565113267622;6692.230393504809;6711.194656641137;6760.391564759196;6805.950459476603;6813.89779153323;6873.1064961554675;6892.21117199548;6922.7923162305215;6729.259781423942;6745.898811517929;6784.21981472137;6804.557703749439;6853.246142924175;6581.177352830896;6604.900926486011;6624.8012559623285;6631.1379520662;6723.159605049369;5501.559875210054;5554.694491802989;5578.163275508706;5583.330000399978;5589.77640894376;6524.771632992823;6776.593404395752;6839.487038472234;6853.623050732058;6861.828454288483;6391.616503009648;6581.440577550962;6636.767713130017;6688.765893193715;6727;6571.704273307617;6595.208574971034;6635.2037755469655;6675;6722.193336799615;6320.462760665395;6511.772087976898;6577.473802095917;6588.484648530961;6613.834654133685;6631.635260060743;6681.665916704048;6702.899456508254;6705.4635629150525;6768.323167683192;6410.775622853364;6437.67811609427;6438.3883531064475;6518.934810651833;6559.376859198559 03:03:43 INFO - PID 30777 | |5;yui.html;882.7440042387707;892.6563273139839;893.2407453664384;908.9681861134861;911.749268951038;17874.73187902181;18871.433856984277;18881.055947202636;18900;21304.14783408662;20092.799072009275;20464.18143274271;20683.276085337016;20714.378568642926;20848.583028339424;933.5752232734089;962.6053318139565;962.6678795815433;963.7879666473365;964.0070727151011;934.6822080492631;968.3318510227955;973.3138137612966;974.4786539201529;974.6783561424749;995.8406654935212;998.6604554451485;1003.0521157506159;1003.2325271167526;1004.2618675273668;994.5723338964242;995.1690338567341;995.372915063509;996.4967691315911;997.2022382094368;735.5807189901758;735.6321839080459;738.7451329291385;738.75621045055;739.7965559471135;17122.82877171237;17273.740893886603;17301.394451194275;17302.22140003703;19396.806031939643;19505.414837554898;19602.313919012908;19963.600727985504;19969;20200.595988080302;15690.9215453922;15900.52298431049;16145;16170.919145404197;16176.75734863978;3693.686036686885;3756.586775454698;3758.605346438612;3758.68051215647;3761.811909404546;119083.4045829774;120228;121249.39375303155;122168.38915805453;124566.37716811449;15300.617484562752;15579.221038948008;15607.921960390126;15625.921870390575;15838.445654402041;29456.705432945826;29536.704632953395;29924;29944.101676949726;30073.548896766566;7264.200937896827;8475.067742548315;8794.824103518022;8960.372773905894;8966.775830604156;3935.193285376491;4000.7599544027453;4011.117554138085;4033.4554835097474;4048.919021619551;4989.476105008995;5054.69671819692;5067.822626208139;5112.463191364927;5113.1052065888325;1030.6289735695143;1036.44550165661;1050.0601961244795;1051.363924825485;1052.9315594486334;678.9762358317436;686.8591938652564;689.8792711275506;702.3538344722867;702.8875379939287;11076;12100.636980890587;12132.878671213351;12659.303738294298;13029.609111726664;674.5446823394287;676.3878689785752;679.1307126877605;682.160942041292;683.8700646877078;5640.717964101778;5741.827745167651;5751.4536119068625;5870.972579798604;5884.470397664145;111826;112457.43771281092;112967.43516282366;114904;116187;99850;102725.91822245345;102928;107180.46409768057;108045.45977270063;87301.56349218213;87472.5626371864;88884.555577223;91620;92035.53982230046;10901.727456813325;11755.88244117548;11904.523819047141;12347.506099755909;13082.084254102121;41657.37513937233;42398.152036959706;42653.36019959765;42808;44039;70468.64765676139;71372.28627713864;72983;73080.04764681574;73615;16933.915330423268;17187.656246874743;17220.311187552357;17555.56111097233;17599.560010999314;21520.139194432046;22066.668999965335;22336.21823236179;22725.204617838295;22740.317790466313;19139.808601913803;19292.71060934059;19421.126049327533;19508.60982780308;19594.216231350587;19693;19860.00699965025;20527.28154514585;20596.27913023037;20635.4841128973;17386.9130654351;17477.475675729747;18786.342478013205;19667.409977700692;19683.507912302306;13425;13626.523071692443;13988.300584970508;14327.57017289483;14418.567442976728;14184.290785460893;14376.712465750417;14779.778303325244;14919.85080149228;15124.092554446768 03:03:43 INFO - PID 30777 | __end_tp_report 03:03:43 INFO - PID 30777 | __start_cc_report 03:03:43 INFO - PID 30777 | _x_x_mozilla_cycle_collect,356 03:03:43 INFO - PID 30777 | __end_cc_report 03:03:43 INFO - PID 30777 | __startTimestamp1504346623259__endTimestamp 03:03:43 INFO - PID 30777 | 03:03:43 INFO - PID 30777 | ------- Summary: start ------- 03:03:43 INFO - PID 30777 | Number of tests: 6 03:03:43 INFO - PID 30777 | 03:03:43 INFO - PID 30777 | [#0] dojo.html Cycles:165 Average:6355.59 Median:6635.24 stddev:754.18 (11.4%) stddev-sans-first:717.43 03:03:43 INFO - PID 30777 | Values: 3301.5 3309.7 3346.7 3366.2 3380.6 6294.1 6364.1 6443.5 6490.7 6539.5 6312.9 6433.9 6434.1 6454.8 6478.7 5858.9 5955.0 5971.8 5995.8 6035.9 5903.4 5967.6 5993.6 6001.0 6012.0 5816.8 5957.0 5961.2 6007.5 6030.0 5736.5 5919.5 5953.3 5967.3 6016.3 5777.5 5826.4 5881.0 5882.7 5898.1 6424.7 6434.3 6438.5 6458.8 6467.9 6824.7 6841.1 6862.1 6914.7 6920.6 6909.2 6910.7 6989.7 7005.3 7038.7 6339.0 6418.7 6422.5 6425.0 6425.2 6956.6 7020.3 7053.4 7063.6 7068.4 6726.9 6727.1 6814.0 6823.0 6823.9 6953.2 6979.7 6982.1 6989.3 6989.6 6856.5 6857.6 6858.2 6945.1 6952.6 6770.6 6814.3 6816.9 6818.8 6871.8 5430.8 5445.0 5446.8 5449.0 5449.4 4343.5 4706.5 4711.5 4771.9 4773.4 6019.2 6048.1 6076.9 6085.9 6086.6 6924.5 6956.2 7019.9 7029.1 7047.8 5960.6 5989.6 6049.3 6073.0 6088.7 5770.0 5850.9 5864.0 5865.2 5885.9 6621.0 6625.1 6659.2 6660.6 6698.0 6844.2 6919.5 6997.2 7049.1 7049.7 6678.7 6693.2 6707.0 6752.1 6771.3 6849.4 6868.8 6915.9 6927.7 6950.6 6815.1 6921.6 6925.3 6934.8 6935.2 6556.6 6643.6 6665.5 6669.2 6672.9 6697.3 6727.5 6729.8 6741.1 6748.8 6687.4 6688.9 6717.3 6717.5 6718.5 6719.4 6780.7 6842.8 6844.3 6847.3 6538.3 6547.7 6630.8 6631.2 6635.2 03:03:43 INFO - PID 30777 | 03:03:43 INFO - PID 30777 | [#1] ext.html Cycles:180 Average:37801.60 Median:3482.14 stddev:53658.65 (1541.0%) stddev-sans-first:53737.08 03:03:43 INFO - PID 30777 | Values: 757.6 840.3 868.8 870.4 1123.4 29959.9 30683.5 39675.4 40439.4 40444.8 41289.6 41736.6 41764.2 41798.8 41809.2 7068.6 7234.5 7417.9 7543.7 7545.6 7474.1 7574.9 7577.0 7579.1 7581.6 7537.1 7589.8 7593.1 7597.1 7598.0 7511.1 7519.3 7561.2 7561.7 7564.4 7538.0 7554.2 7574.3 7577.9 7579.2 36727.6 36795.0 36891.3 36905.6 36950.8 3470.4 3473.7 3490.6 3497.8 3502.1 30085.8 30313.8 30335.1 30476.5 30516.9 4961.2 5194.5 5208.0 5229.6 5232.8 188995.0 190626.0 191119.0 191254.0 191543.0 46534.1 46591.3 46623.5 46738.3 46796.5 51033.7 51848.2 51907.2 51959.0 52031.5 15733.5 15916.8 16119.8 16149.0 16151.4 11124.4 11285.2 12915.2 13065.0 13172.2 14178.7 14179.0 14331.4 14475.3 14531.4 5724.3 5758.3 5781.7 5830.3 5923.8 990.0 995.6 1000.8 1016.8 1033.7 21501.8 22950.5 28460.7 28692.9 28952.6 1093.8 1102.5 1104.0 1106.9 1110.0 12184.1 15525.8 15603.5 15618.1 15792.0 222429.0 225306.0 225598.0 225611.0 225631.0 53773.7 54295.5 54545.5 54618.0 54932.0 1430.3 1461.3 1469.7 1477.2 1487.9 26357.6 26655.1 26952.9 27038.5 27109.7 37365.3 37660.8 37790.4 37803.0 37939.0 202694.0 204407.0 204920.0 206595.0 206960.0 33985.5 34315.5 34319.3 36164.3 36179.3 44386.3 44470.0 44483.0 44767.8 44783.8 32340.7 32724.3 32852.3 33071.7 33185.0 31992.5 32285.7 32302.0 32492.0 32855.2 36393.5 36413.8 36426.8 37053.8 37090.0 31398.4 31894.0 32099.5 32179.7 32441.8 23351.3 23845.5 23865.4 23967.5 24439.5 03:03:43 INFO - PID 30777 | 03:03:43 INFO - PID 30777 | [#2] jquery.html Cycles:180 Average:34541.62 Median:22552.99 stddev:81741.70 (362.4%) stddev-sans-first:81931.19 03:03:43 INFO - PID 30777 | Values: 561.2 561.2 567.0 570.8 576.3 21175.7 22126.2 22259.7 22267.6 22369.4 23529.8 25861.7 25982.9 26079.6 26158.7 6424.6 6614.1 6614.7 6615.1 6615.5 6428.8 6510.5 6575.7 6670.4 6710.2 6658.9 6668.2 6669.1 6673.4 6674.9 6671.6 6677.6 6685.2 6685.9 6690.0 6195.4 6204.7 6206.3 6211.7 6214.3 28199.9 29002.9 29341.1 29673.3 29981.3 2044.8 2048.6 2060.1 2071.1 2086.5 19459.3 20088.0 20101.1 20104.1 20105.2 2383.7 2416.5 2460.1 2478.2 2485.7 135941.3 139857.0 140391.0 140594.0 140981.0 13984.6 14342.3 14349.3 14898.9 15172.5 22285.2 23141.2 23572.8 23584.5 23606.3 9637.9 9943.6 9999.8 10015.7 10018.4 9074.9 9170.8 9210.2 9212.4 9248.2 9197.5 9216.0 9324.0 9337.3 9564.7 2571.8 2573.3 2594.6 2596.8 2746.7 947.5 951.7 993.9 1012.8 1021.3 19490.6 19792.8 19859.9 20139.8 20613.7 1021.7 1051.9 1052.9 1070.0 1074.6 10567.6 10884.2 10899.1 11013.1 11082.8 473979.0 480844.0 487453.0 487527.0 489884.0 114004.4 117920.0 119649.0 120147.0 120238.6 1179.1 1213.8 1214.5 1219.6 1228.8 16857.9 16985.2 17282.3 17400.7 17560.2 50776.7 51642.5 52013.2 53582.2 54345.7 69896.3 70241.6 70433.6 70854.6 70860.3 14367.9 14549.3 14619.5 14655.3 14683.2 22354.9 22736.5 22813.1 22976.5 23043.5 18080.9 18192.6 18233.5 18473.8 18576.9 16596.2 16726.6 16779.7 17075.3 17352.8 18640.5 18686.3 18810.9 18940.9 18950.1 14298.9 14305.5 14360.6 14460.3 14492.6 17878.8 18132.8 18220.2 18313.9 18371.7 03:03:43 INFO - PID 30777 | 03:03:43 INFO - PID 30777 | [#3] mootools.html Cycles:180 Average:9298.84 Median:2717.49 stddev:6674.05 (245.6%) stddev-sans-first:6665.76 03:03:43 INFO - PID 30777 | Values: 1307.3 1361.3 1367.8 1371.4 1372.5 14691.7 15303.5 15309.0 15327.1 15335.5 14736.2 15654.7 15733.4 15749.1 15766.7 2728.6 2771.9 2778.2 2780.5 2784.3 2732.9 2842.3 2848.4 2848.7 2849.2 2722.0 2722.5 2751.7 2757.4 2807.1 2682.7 2713.0 2737.1 2738.8 2750.1 2672.0 2674.9 2682.8 2687.7 2698.2 14825.5 15000.8 15144.8 15150.5 15175.5 7764.7 8075.2 8113.2 8115.9 8118.2 5282.8 5432.5 5511.9 5527.1 5571.6 1946.8 1950.1 1951.1 1993.0 1996.2 23753.5 24238.2 26297.7 26773.7 26872.5 6944.8 7038.9 7070.6 7100.3 7133.3 17127.0 17182.5 17299.1 17300.5 17328.3 7846.7 7964.0 7995.0 8005.5 8046.6 7389.2 7392.1 7394.2 7419.6 7434.9 5222.1 5400.7 5406.8 5418.4 5456.7 1611.8 1620.0 1631.0 1631.1 1664.6 861.4 880.4 890.2 896.3 906.6 7339.7 7404.0 7416.3 7422.5 7581.8 658.0 660.1 664.4 672.1 672.8 4991.6 5137.4 5173.6 5214.1 5316.2 21263.4 21603.8 22036.3 22123.6 22415.0 21506.0 21557.4 21720.3 21966.2 21994.1 9727.4 10030.2 10836.7 11020.3 11112.9 8528.1 8534.9 8544.4 8572.4 8601.7 18694.6 18910.2 19102.0 19146.0 19545.1 19122.1 19158.9 19465.1 19569.6 19740.1 11581.6 11811.2 11839.0 11843.6 11851.3 13084.6 13676.9 14144.2 14214.8 14219.4 10750.5 10805.6 10863.0 10921.3 11146.1 10971.8 11006.0 11033.2 11259.3 11301.5 9139.5 9356.3 9449.2 9466.1 9520.9 6679.3 6741.2 6773.7 6839.0 6879.0 6748.5 6861.5 6876.5 6991.4 7027.3 03:03:43 INFO - PID 30777 | 03:03:43 INFO - PID 30777 | [#4] prototype.html Cycles:180 Average:5728.62 Median:6325.57 stddev:1544.01 (24.4%) stddev-sans-first:1497.85 03:03:43 INFO - PID 30777 | Values: 511.1 512.4 524.0 531.3 538.0 6451.8 6472.6 6529.3 6563.4 6565.2 6290.6 6308.9 6346.3 6395.5 6399.3 5240.7 5291.5 5297.6 5319.9 5327.0 4852.9 5199.1 5223.9 5312.2 5337.6 5002.3 5015.6 5034.5 5038.6 5079.4 4977.6 5028.8 5035.0 5041.4 5060.5 4512.4 4530.5 4590.3 4818.8 4933.7 6268.7 6281.3 6314.6 6330.7 6331.7 6046.1 6138.8 6155.7 6157.4 6178.9 6763.6 6782.9 6822.4 6869.8 6885.8 5382.4 5424.8 5445.3 5446.8 5468.6 6783.6 6887.6 6943.5 7013.6 7212.5 5509.8 5569.1 5605.6 5622.8 5640.9 5645.5 5669.4 5685.6 5696.1 5754.6 6093.5 6111.4 6127.9 6149.3 6191.7 6781.4 6858.6 6889.8 6895.8 6917.2 1956.5 1988.5 1995.8 2000.6 2012.8 1340.7 1440.7 1475.4 1481.2 1491.6 5857.9 5864.2 5868.9 5886.9 6011.7 6763.5 6891.3 6915.8 6927.5 6964.1 5766.4 5832.8 5840.6 5892.5 5906.0 3612.9 3631.2 3639.8 3642.6 3645.3 6616.2 6656.6 6661.4 6700.3 6715.3 6951.3 7020.2 7032.0 7077.5 7117.1 6661.9 6690.6 6692.2 6711.2 6760.4 6806.0 6813.9 6873.1 6892.2 6922.8 6729.3 6745.9 6784.2 6804.6 6853.2 6581.2 6604.9 6624.8 6631.1 6723.2 5501.6 5554.7 5578.2 5583.3 5589.8 6524.8 6776.6 6839.5 6853.6 6861.8 6391.6 6581.4 6636.8 6688.8 6727.0 6571.7 6595.2 6635.2 6675.0 6722.2 6320.5 6511.8 6577.5 6588.5 6613.8 6631.6 6681.7 6702.9 6705.5 6768.3 6410.8 6437.7 6438.4 6518.9 6559.4 03:03:43 INFO - PID 30777 | 03:03:43 INFO - PID 30777 | [#5] yui.html Cycles:180 Average:24114.53 Median:21076.37 stddev:32729.82 (155.3%) stddev-sans-first:32775.14 03:03:43 INFO - PID 30777 | Values: 882.7 892.7 893.2 909.0 911.7 17874.7 18871.4 18881.1 18900.0 21304.1 20092.8 20464.2 20683.3 20714.4 20848.6 933.6 962.6 962.7 963.8 964.0 934.7 968.3 973.3 974.5 974.7 995.8 998.7 1003.1 1003.2 1004.3 994.6 995.2 995.4 996.5 997.2 735.6 735.6 738.7 738.8 739.8 17122.8 17273.7 17301.4 17302.2 19396.8 19505.4 19602.3 19963.6 19969.0 20200.6 15690.9 15900.5 16145.0 16170.9 16176.8 3693.7 3756.6 3758.6 3758.7 3761.8 119083.4 120228.0 121249.4 122168.4 124566.4 15300.6 15579.2 15607.9 15625.9 15838.4 29456.7 29536.7 29924.0 29944.1 30073.5 7264.2 8475.1 8794.8 8960.4 8966.8 3935.2 4000.8 4011.1 4033.5 4048.9 4989.5 5054.7 5067.8 5112.5 5113.1 1030.6 1036.4 1050.1 1051.4 1052.9 679.0 686.9 689.9 702.4 702.9 11076.0 12100.6 12132.9 12659.3 13029.6 674.5 676.4 679.1 682.2 683.9 5640.7 5741.8 5751.5 5871.0 5884.5 111826.0 112457.4 112967.4 114904.0 116187.0 99850.0 102725.9 102928.0 107180.5 108045.5 87301.6 87472.6 88884.6 91620.0 92035.5 10901.7 11755.9 11904.5 12347.5 13082.1 41657.4 42398.2 42653.4 42808.0 44039.0 70468.6 71372.3 72983.0 73080.0 73615.0 16933.9 17187.7 17220.3 17555.6 17599.6 21520.1 22066.7 22336.2 22725.2 22740.3 19139.8 19292.7 19421.1 19508.6 19594.2 19693.0 19860.0 20527.3 20596.3 20635.5 17386.9 17477.5 18786.3 19667.4 19683.5 13425.0 13626.5 13988.3 14327.6 14418.6 14184.3 14376.7 14779.8 14919.9 15124.1 03:03:43 INFO - PID 30777 | -------- Summary: end -------- 03:03:43 INFO - PID 30777 | 03:03:43 INFO - TEST-INFO | 30777: exit 0 03:03:43 INFO - TEST-OK | dromaeo_css | took 1110884ms 03:03:43 INFO - TEST-START | kraken 03:03:43 INFO - Initialising browser for kraken test... 03:03:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43480/getInfo.html -profile /tmp/tmpRRzLuE/profile 03:03:44 INFO - TEST-INFO | started process 31149 (/builds/slave/test/build/application/firefox/firefox http://localhost:43480/getInfo.html) 03:03:49 INFO - TEST-INFO | 31149: exit 0 03:03:50 INFO - Browser initialized. 03:03:50 INFO - Running cycle 1/1 for kraken test... 03:03:50 INFO - Using env: {'DISPLAY': ':0', 03:03:50 INFO - 'HOME': '/home/cltbld', 03:03:50 INFO - 'JSGC_DISABLE_POISONING': '1', 03:03:50 INFO - 'LANG': 'en_US.UTF-8', 03:03:50 INFO - 'LANGUAGE': 'en_US:en', 03:03:50 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:03:50 INFO - 'LOGNAME': 'cltbld', 03:03:50 INFO - 'MAIL': '/var/mail/cltbld', 03:03:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:03:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:03:50 INFO - 'MOZ_CRASHREPORTER': '1', 03:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:50 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:03:50 INFO - 'MOZ_NO_REMOTE': '1', 03:03:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:03:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:50 INFO - 'NO_EM_RESTART': '1', 03:03:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:50 INFO - 'PWD': '/builds/slave/test', 03:03:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:03:50 INFO - 'RUST_BACKTRACE': 'full', 03:03:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:03:50 INFO - 'SHELL': '/bin/bash', 03:03:50 INFO - 'SHLVL': '1', 03:03:50 INFO - 'TERM': 'linux', 03:03:50 INFO - 'TMOUT': '86400', 03:03:50 INFO - 'USER': 'cltbld', 03:03:50 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504345444.778711-834712415', 03:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:50 INFO - '_': '/tools/buildbot/bin/python'} 03:03:50 INFO - TEST-INFO | started process 31338 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRRzLuE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:03:51 INFO - PID 31338 | 03:03:51 INFO - PID 31338 | (/builds/slave/test/build/application/firefox/firefox:31389): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:03:51 INFO - PID 31338 | 03:04:01 INFO - PID 31338 | RSS: Main: 168169472 03:04:01 INFO - PID 31338 | 03:04:03 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43480/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:04 INFO - PID 31338 | RSS: Main: 174456832 03:04:04 INFO - PID 31338 | 03:04:07 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43480/tests/kraken/driver.html?testName=audio-dft) 03:04:08 INFO - PID 31338 | RSS: Main: 178302976 03:04:08 INFO - PID 31338 | 03:04:11 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43480/tests/kraken/driver.html?testName=audio-fft) 03:04:11 INFO - PID 31338 | RSS: Main: 171253760 03:04:11 INFO - PID 31338 | 03:04:13 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43480/tests/kraken/driver.html?testName=audio-oscillator) 03:04:14 INFO - PID 31338 | RSS: Main: 171745280 03:04:14 INFO - PID 31338 | 03:04:16 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43480/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:04:17 INFO - PID 31338 | RSS: Main: 171671552 03:04:17 INFO - PID 31338 | 03:04:20 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43480/tests/kraken/driver.html?testName=imaging-darkroom) 03:04:20 INFO - PID 31338 | RSS: Main: 173834240 03:04:20 INFO - PID 31338 | 03:04:24 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43480/tests/kraken/driver.html?testName=imaging-desaturate) 03:04:24 INFO - PID 31338 | RSS: Main: 172998656 03:04:24 INFO - PID 31338 | 03:04:27 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43480/tests/kraken/driver.html?testName=json-parse-financial) 03:04:28 INFO - PID 31338 | RSS: Main: 173051904 03:04:28 INFO - PID 31338 | 03:04:30 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43480/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:04:30 INFO - PID 31338 | RSS: Main: 172793856 03:04:30 INFO - PID 31338 | 03:04:32 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43480/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:04:32 INFO - PID 31338 | RSS: Main: 172605440 03:04:32 INFO - PID 31338 | 03:04:35 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43480/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:04:35 INFO - PID 31338 | RSS: Main: 169148416 03:04:35 INFO - PID 31338 | 03:04:38 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43480/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:04:39 INFO - PID 31338 | RSS: Main: 169414656 03:04:39 INFO - PID 31338 | 03:04:41 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43480/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:04:42 INFO - PID 31338 | RSS: Main: 170532864 03:04:42 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | Cycle 1(1): loaded http://localhost:43480/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43480/tests/kraken/driver.html?testName=ai-astar) 03:04:44 INFO - PID 31338 | RSS: Main: 171839488 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | __start_tp_report 03:04:44 INFO - PID 31338 | _x_x_mozilla_page_load 03:04:44 INFO - PID 31338 | _x_x_mozilla_page_load_details 03:04:44 INFO - PID 31338 | |i|pagename|runs| 03:04:44 INFO - PID 31338 | |0;ai-astar;98;109;100;108;109;113;108;98;108;110 03:04:44 INFO - PID 31338 | |1;audio-beat-detection;148;117;141;170;117;143;141;121;174;122 03:04:44 INFO - PID 31338 | |2;audio-dft;162;162;163;164;163;164;165;171;169;161 03:04:44 INFO - PID 31338 | |3;audio-fft;75;83;73;174;75;77;74;76;156;76 03:04:44 INFO - PID 31338 | |4;audio-oscillator;79;79;130;79;80;79;122;79;79;125 03:04:44 INFO - PID 31338 | |5;imaging-gaussian-blur;114;114;114;114;114;114;114;113;115;114 03:04:44 INFO - PID 31338 | |6;imaging-darkroom;149;149;149;148;150;148;148;148;147;149 03:04:44 INFO - PID 31338 | |7;imaging-desaturate;94;99;100;93;94;94;94;93;93;94 03:04:44 INFO - PID 31338 | |8;json-parse-financial;53;53;53;53;65;52;53;54;53;53 03:04:44 INFO - PID 31338 | |9;json-stringify-tinderbox;39;38;39;38;38;38;38;39;39;38 03:04:44 INFO - PID 31338 | |10;stanford-crypto-aes;74;76;74;74;77;74;74;76;76;76 03:04:44 INFO - PID 31338 | |11;stanford-crypto-ccm;130;124;133;125;125;125;118;121;120;151 03:04:44 INFO - PID 31338 | |12;stanford-crypto-pbkdf2;172;174;175;174;170;171;172;178;170;179 03:04:44 INFO - PID 31338 | |13;stanford-crypto-sha256-iterative;66;65;66;67;68;64;64;65;63;62 03:04:44 INFO - PID 31338 | __end_tp_report 03:04:44 INFO - PID 31338 | __start_cc_report 03:04:44 INFO - PID 31338 | _x_x_mozilla_cycle_collect,918 03:04:44 INFO - PID 31338 | __end_cc_report 03:04:44 INFO - PID 31338 | __startTimestamp1504346684605__endTimestamp 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | ------- Summary: start ------- 03:04:44 INFO - PID 31338 | Number of tests: 14 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#0] ai-astar Cycles:10 Average:106.10 Median:109.50 stddev:5.36 (4.9%) stddev-sans-first:4.82 03:04:44 INFO - PID 31338 | Values: 98.0 109.0 100.0 108.0 109.0 113.0 108.0 98.0 108.0 110.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:142.00 stddev:20.74 (14.6%) stddev-sans-first:21.76 03:04:44 INFO - PID 31338 | Values: 148.0 117.0 141.0 170.0 117.0 143.0 141.0 121.0 174.0 122.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.00 stddev:3.20 (2.0%) stddev-sans-first:3.28 03:04:44 INFO - PID 31338 | Values: 162.0 162.0 163.0 164.0 163.0 164.0 165.0 171.0 169.0 161.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#3] audio-fft Cycles:10 Average:93.90 Median:75.50 stddev:37.81 (50.1%) stddev-sans-first:39.48 03:04:44 INFO - PID 31338 | Values: 75.0 83.0 73.0 174.0 75.0 77.0 74.0 76.0 156.0 76.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:79.00 stddev:22.56 (28.6%) stddev-sans-first:23.34 03:04:44 INFO - PID 31338 | Values: 79.0 79.0 130.0 79.0 80.0 79.0 122.0 79.0 79.0 125.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 03:04:44 INFO - PID 31338 | Values: 114.0 114.0 114.0 114.0 114.0 114.0 114.0 113.0 115.0 114.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 03:04:44 INFO - PID 31338 | Values: 149.0 149.0 149.0 148.0 150.0 148.0 148.0 148.0 147.0 149.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.00 stddev:2.53 (2.7%) stddev-sans-first:2.67 03:04:44 INFO - PID 31338 | Values: 94.0 99.0 100.0 93.0 94.0 94.0 94.0 93.0 93.0 94.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:53.00 stddev:3.82 (7.2%) stddev-sans-first:4.03 03:04:44 INFO - PID 31338 | Values: 53.0 53.0 53.0 53.0 65.0 52.0 53.0 54.0 53.0 53.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.50 stddev:0.52 (1.3%) stddev-sans-first:0.50 03:04:44 INFO - PID 31338 | Values: 39.0 38.0 39.0 38.0 38.0 38.0 38.0 39.0 39.0 38.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:76.00 stddev:1.20 (1.6%) stddev-sans-first:1.20 03:04:44 INFO - PID 31338 | Values: 74.0 76.0 74.0 74.0 77.0 74.0 74.0 76.0 76.0 76.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#11] stanford-crypto-ccm Cycles:10 Average:127.20 Median:125.00 stddev:9.47 (7.6%) stddev-sans-first:9.99 03:04:44 INFO - PID 31338 | Values: 130.0 124.0 133.0 125.0 125.0 125.0 118.0 121.0 120.0 151.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:174.00 stddev:3.14 (1.8%) stddev-sans-first:3.28 03:04:44 INFO - PID 31338 | Values: 172.0 174.0 175.0 174.0 170.0 171.0 172.0 178.0 170.0 179.0 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.50 stddev:1.83 (2.8%) stddev-sans-first:1.90 03:04:44 INFO - PID 31338 | Values: 66.0 65.0 66.0 67.0 68.0 64.0 64.0 65.0 63.0 62.0 03:04:44 INFO - PID 31338 | -------- Summary: end -------- 03:04:44 INFO - PID 31338 | 03:04:44 INFO - PID 31338 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:04:45 INFO - TEST-INFO | 31338: exit 0 03:04:45 INFO - TEST-OK | kraken | took 61353ms 03:04:45 INFO - SUITE-END | took 1172s 03:04:45 INFO - Completed test suite (00:19:32) 03:04:45 INFO - javascript benchmark 03:04:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8637.839380311023, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.521279414485, 3309.659587866914, 3346.7322614190866, 3366.2257680733424, 3380.5943286805586, 6294.087357333184, 6364.109024736535, 6443.548951573392, 6490.707918143683, 6539.542232043759, 6312.9368706313035, 6433.903491447632, 6434.099226108348, 6454.774082907099, 6478.74085036598, 5858.941410585893, 5955.047192449209, 5971.790987315445, 5995.820125396245, 6035.909461358061, 5903.380145084769, 5967.582269241155, 5993.550483713717, 6001.0, 6011.969940150315, 5816.796412125576, 5956.970215148918, 5961.225040744697, 6007.4893634041155, 6029.969850150765, 5736.541076713853, 5919.49684276837, 5953.3451320354725, 5967.313758917737, 6016.308124565688, 5777.508911742507, 5826.446487583673, 5881.0, 5882.6764527950945, 5898.144769008512, 6424.74301027963, 6434.324395938406, 6438.484921206292, 6458.773942912023, 6467.902981455282, 6824.6928888200155, 6841.144856892888, 6862.073620061277, 6914.723411063552, 6920.550164239308, 6909.170899492043, 6910.654467276667, 6989.720411183546, 7005.334493223136, 7038.718451261943, 6339.0, 6418.743250269984, 6422.518311126684, 6425.036244563353, 6425.1647285853005, 6956.582605043714, 7020.262872398427, 7053.4004609608755, 7063.646817659199, 7068.363847253669, 6726.932730672631, 6727.058211850345, 6814.046033555306, 6823.0, 6823.863522729517, 6953.165620125617, 6979.6859141337745, 6982.057422248033, 6989.33601307875, 6989.580625162506, 6856.485763567753, 6857.55425897315, 6858.211305699809, 6945.131858517685, 6952.617606031616, 6770.593764374154, 6814.318568143245, 6816.863662726718, 6818.829529261808, 6871.759488417982, 5430.83707488776, 5444.972775136178, 5446.809361672323, 5448.972755136199, 5449.4005659377435, 4343.5439278875265, 4706.529347065266, 4711.528847115261, 4771.928421073687, 4773.355596994361, 6019.157317975487, 6048.092786082035, 6076.8784624308155, 6085.939140608537, 6086.60437071589, 6924.549904256207, 6956.165260168812, 7019.92980070203, 7029.05107810439, 7047.823804404931, 5960.612560183574, 5989.610675306091, 6049.304330002018, 6073.0283154695035, 6088.665123418255, 5770.047942089592, 5850.853728656818, 5863.9738045841805, 5865.20819689345, 5885.852853678607, 6621.039949207338, 6625.105610742585, 6659.167604049494, 6660.6336651484635, 6697.966510167418, 6844.247132815386, 6919.515633905779, 6997.230304666381, 7049.118860142482, 7049.682764275723, 6678.699458524281, 6693.163354580677, 6706.9999999998045, 6752.054712340375, 6771.322867713387, 6849.383555480031, 6868.759593414206, 6915.896261556181, 6927.7228910845015, 6950.582965022115, 6815.079964204769, 6921.584704917721, 6925.342092501204, 6934.757283495053, 6935.202451718117, 6556.63938483388, 6643.634600097041, 6665.533412661067, 6669.199696036507, 6672.933270667231, 6697.330266973264, 6727.495437842279, 6729.831754206184, 6741.056252124805, 6748.763793267212, 6687.398134167948, 6688.899666504909, 6717.261101278757, 6717.529772915849, 6718.462522998246, 6719.361660642231, 6780.72877084911, 6842.794716158523, 6844.315568443315, 6847.281035491202, 6538.346165383424, 6547.70535325902, 6630.83422914431, 6631.237407697983, 6635.23694775107], "value": 6299.437700146461, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.5909009135067, 840.3067469337796, 868.7654333330001, 870.438567124205, 1123.3821398230982, 29959.85020074899, 30683.539746903814, 39675.404868926955, 40439.393409098884, 40444.79777601115, 41289.58710412902, 41736.58263417365, 41764.16471670565, 41798.79100604493, 41809.163816723645, 7068.646567671622, 7234.457415693818, 7417.888731669016, 7543.69825206991, 7545.622718864062, 7474.065741782278, 7574.9242507575045, 7577.0, 7579.052618422697, 7581.620918954058, 7537.057867766529, 7589.848203035936, 7593.05086864142, 7597.126330471983, 7598.0, 7511.06111736033, 7519.323260906517, 7561.24387561245, 7561.697532098709, 7564.357029652486, 7537.9623101884135, 7554.244575542401, 7574.3183113520045, 7577.886331705029, 7579.1662917079075, 36727.63272367269, 36795.0, 36891.26217475635, 36905.630943690754, 36950.81524592387, 3470.35798377301, 3473.7221022318154, 3490.5636795400574, 3497.7726447780815, 3502.124468882779, 30085.849570752005, 30313.848430757706, 30335.089947301618, 30476.54285185724, 30516.931907383358, 4961.231009193581, 5194.532492075731, 5208.0, 5229.607779416521, 5232.843014709565, 188995.0, 190626.0, 191119.0, 191254.04372978047, 191543.0, 46534.069318614114, 46591.30113048307, 46623.53376466192, 46738.298925516145, 46796.5320346799, 51033.74483127561, 51848.22227666588, 51907.22139167915, 51959.0, 52031.47968520342, 15733.527994160193, 15916.76124858128, 16119.758203626954, 16149.0, 16151.434699785686, 11124.443777811239, 11285.21003529745, 12915.16051456671, 13065.0, 13172.209667419986, 14178.71642567143, 14179.007469477037, 14331.426742930165, 14475.348609312607, 14531.418743250151, 5724.25584673998, 5758.309002919593, 5781.682007489629, 5830.271216097988, 5923.822285331447, 989.9704307520242, 995.621663767778, 1000.8398656214971, 1016.8373060310315, 1033.6537260017828, 21501.78498215029, 22950.540989180125, 28460.71539284622, 28692.856535717186, 28952.565711514344, 1093.814051611212, 1102.5148934468807, 1104.0, 1106.9280496767685, 1109.9611513597147, 12184.08619353552, 15525.844741552666, 15603.531894043197, 15618.062916225063, 15792.0, 222429.0, 225306.0, 225598.0, 225611.0, 225631.0, 53773.731131344095, 54295.45704542983, 54545.45454545483, 54618.0, 54932.0, 1430.2777097565663, 1461.3058797071305, 1469.7281003014477, 1477.1506383829126, 1487.9256037198313, 26357.60463593086, 26655.067072652364, 26952.86523567448, 27038.45923081488, 27109.72890271072, 37365.252694945404, 37660.811695942444, 37790.43314350342, 37803.0, 37939.0, 202694.0, 204407.0, 204920.0, 206595.0, 206960.0, 33985.490217647246, 34315.485267720505, 34319.31361372808, 36164.27671446609, 36179.27641447209, 44386.334204987594, 44470.0, 44483.0, 44767.776161118985, 44783.77608111939, 32340.676593234708, 32724.345513089127, 32852.342953141284, 33071.66928330782, 33185.0, 31992.520112198803, 32285.677143228266, 32302.0, 32492.0, 32855.17862053468, 36393.45409818802, 36413.817930910176, 36426.81786591156, 37053.81473092725, 37090.0, 31398.372032559677, 31894.0, 32099.518507222878, 32179.678203218606, 32441.837790810896, 23351.299461016195, 23845.52308953846, 23865.403364916016, 23967.52064958726, 24439.511209775348], "value": 17301.29639609798, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.1582626060908, 561.1946856261263, 566.9631473954195, 570.840164753869, 576.3343338444097, 21175.68236476452, 22126.22558210463, 22259.66610500842, 22267.554648907033, 22369.440763980907, 23529.764702353015, 25861.74138258617, 25982.870085649545, 26079.60880586788, 26158.738412615916, 6424.550281480287, 6614.107095542088, 6614.669266536678, 6615.140031795884, 6615.5369124161325, 6428.839279018039, 6510.5442619016685, 6575.671216439159, 6670.433013193884, 6710.228323742784, 6658.933410665904, 6668.166479190101, 6669.066330713704, 6673.399394054559, 6674.899876501856, 6671.566348187353, 6677.632730199837, 6685.164354455717, 6685.866282674319, 6689.966550167218, 6195.380461953769, 6204.689765511706, 6206.317305096436, 6211.658358790265, 6214.316425193225, 28199.85900070507, 29002.854985725146, 29341.119766407042, 29673.25816854596, 29981.25046873824, 2044.8159665630017, 2048.621005114059, 2060.093558834123, 2071.1301253473575, 2086.54096098858, 19459.318923837596, 20088.0, 20101.095450704754, 20104.09531571068, 20105.195792168295, 2383.6928304857556, 2416.5408572371193, 2460.1266550374717, 2478.2069737683946, 2485.701715794098, 135941.3202933989, 139857.0, 140391.0, 140594.0, 140981.0, 13984.580462586138, 14342.282885855666, 14349.282535873164, 14898.925505372621, 15172.544823655307, 22285.220017299318, 23141.19005834788, 23572.7642723574, 23584.528309433714, 23606.285277193343, 9637.855432168522, 9943.602255909682, 9999.750006249902, 10015.749606259757, 10018.39889606626, 9074.86387704185, 9170.761947137185, 9210.171084602418, 9212.447253164832, 9248.16766491019, 9197.516670499062, 9215.986241513428, 9323.953380233055, 9337.253019758404, 9564.713058608253, 2571.781398581104, 2573.343797331706, 2594.5718956372366, 2596.818222724391, 2746.7115952824665, 947.4552132523827, 951.6859436385976, 993.8707967964118, 1012.7974405118858, 1021.2748948246827, 19490.610187796166, 19792.802071979382, 19859.91470895535, 20139.79860201379, 20613.690794638092, 1021.7343490692474, 1051.9053285204368, 1052.867942416597, 1070.0476575847501, 1074.6292529077455, 10567.577296908192, 10884.238103332691, 10899.073578745736, 11013.07271227532, 11082.778344433065, 473979.0, 480844.0, 487453.0, 487527.0, 489884.0, 114004.42997784958, 117920.0, 119649.0, 120147.0, 120238.59522809494, 1179.0508640544272, 1213.8239955206445, 1214.4899142360052, 1219.6402061391912, 1228.8095345221504, 16857.91571042186, 16985.23566439538, 17282.308707651555, 17400.73898891543, 17560.209790559198, 50776.74611626918, 51642.483575163766, 52013.219801702246, 53582.196267055246, 54345.72827135839, 69896.30103699102, 70241.64879175776, 70433.64783176256, 70854.64572677104, 70860.29139708537, 14367.856321436651, 14549.345279462237, 14619.488317908823, 14655.26723663834, 14683.19242441676, 22354.888225558767, 22736.545269094193, 22813.087476500754, 22976.54046919019, 23043.539129216988, 18080.90959545194, 18192.636147276717, 18233.452996410128, 18473.81526184721, 18576.907115464335, 16596.170191490135, 16726.58183545421, 16779.664406711552, 17075.31698732037, 17352.82647173512, 18640.533986650444, 18686.34597789071, 18810.905945470186, 18940.905295473436, 18950.052497375353, 14298.928505357406, 14305.499307524187, 14360.640983975483, 14460.27698615086, 14492.637684057561, 17878.821211787716, 18132.818671813115, 18220.18009189616, 18313.908430457763, 18371.724424133918], "value": 11943.775752684196, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.2548647271055, 1361.278522383136, 1367.835859696836, 1371.4308561946793, 1372.5470594703745, 14691.706165876689, 15303.464378746747, 15309.0, 15327.080375177484, 15335.539933801976, 14736.189509576969, 15654.686906261868, 15733.449329273477, 15749.055056696578, 15766.684666306666, 2728.577070554062, 2771.9168424947284, 2778.2220978126156, 2780.4995100881833, 2784.3456787654886, 2732.931676708078, 2842.3036356092784, 2848.416074704691, 2848.7151284871557, 2849.244950088228, 2721.9591706124324, 2722.4555088982183, 2751.697313295536, 2757.352022274774, 2807.143821134553, 2682.7183145769704, 2713.02331160781, 2737.083077169151, 2738.7535121838932, 2750.0787236275874, 2671.9599206011926, 2674.946501069968, 2682.8122031457806, 2687.7177896320995, 2698.1905428371606, 14825.48110816127, 15000.774988375184, 15144.848551514564, 15150.469733559383, 15175.468858589897, 7764.689412423496, 8075.192480751936, 8113.229243221885, 8115.8782618260775, 8118.228768267005, 5282.815101471469, 5432.538234250093, 5511.862203444906, 5527.088030474967, 5571.554275657938, 1946.7858535561077, 1950.1224448997866, 1951.0829909942304, 1992.9601407971904, 1996.2314508914028, 23753.524929501313, 24238.151664691828, 26297.73702262972, 26773.73226267732, 26872.462550748874, 6944.826379340557, 7038.92961070393, 7070.575765454089, 7100.254473280231, 7133.251008644018, 17127.0, 17182.484525464257, 17299.13504324804, 17300.48098557045, 17328.30686772515, 7846.725364612325, 7964.044314682275, 7994.960025199953, 8005.519668819773, 8046.597670116471, 7389.1502477214735, 7392.112946446461, 7394.186639469654, 7419.554826710415, 7434.851302973911, 5222.1383471727495, 5400.702961337164, 5406.810761623325, 5418.431064738224, 5456.727163641802, 1611.7662938873798, 1619.9919000404923, 1630.9755353669705, 1631.1191956343441, 1664.5672125247281, 861.3625916821633, 880.4101252160939, 890.2210565754964, 896.2650626486349, 906.5648488725317, 7339.669714862844, 7403.962980185064, 7416.295451932057, 7422.48042637021, 7581.810454738676, 657.9736810527621, 660.1154453032959, 664.4153145232165, 672.0926748889059, 672.8351553869236, 4991.550760431579, 5137.434882162959, 5173.611979101583, 5214.087534681492, 5316.229146773773, 21263.36209913705, 21603.783962160178, 22036.33890983273, 22123.557528849335, 22415.0, 21506.0, 21557.35327940133, 21720.348389548024, 21966.231181908555, 21994.120235190734, 9727.41635501858, 10030.247731420026, 10836.67489975302, 11020.283681560673, 11112.944435277772, 8528.14718528167, 8534.871976920474, 8544.444611100258, 8572.442791218551, 8601.741947741577, 18694.626107478045, 18910.243590256785, 19102.0, 19146.0, 19545.12046957862, 19122.139503722643, 19158.904205478884, 19465.124069417194, 19569.60860782748, 19740.111694973475, 11581.594644187413, 11811.173217874666, 11839.0, 11843.644690659294, 11851.288922664668, 13084.607461776162, 13676.863231367559, 14144.151350918977, 14214.786778198128, 14219.431222750974, 10750.462476875968, 10805.621803237163, 10863.022327990519, 10921.344719316867, 11146.108311335236, 10971.83542246851, 11006.0, 11033.172512061466, 11259.268147570381, 11301.491432885705, 9139.45163290178, 9356.251499879858, 9449.244060475008, 9466.100720431548, 9520.90479095228, 6679.298673639198, 6741.22475915276, 6773.695183716646, 6838.965805170942, 6879.0, 6748.460123190232, 6861.4510839133745, 6876.484263680344, 6991.405730512964, 7027.297270272932], "value": 6607.856158421755, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.1106674386566, 512.4337606944329, 523.960702947279, 531.3464438740349, 537.9650322729022, 6451.838704032401, 6472.644004579746, 6529.314421985694, 6563.376479234471, 6565.227388605073, 6290.622562646245, 6308.886523014784, 6346.333634968331, 6395.488360931126, 6399.328070552595, 5240.737963101839, 5291.523762861341, 5297.602679799012, 5319.867003324909, 5327.0, 4852.902941941159, 5199.14214154664, 5223.895522089556, 5312.229726689628, 5337.599680023995, 5002.324686167357, 5015.5736762375245, 5034.5468907798295, 5038.571721403685, 5079.390473143228, 4977.626677999128, 5028.823991160292, 5034.974825125888, 5041.445441001487, 5060.493950604947, 4512.368268442421, 4530.456345238559, 4590.311453282001, 4818.7831547580445, 4933.7286449245275, 6268.717907694165, 6281.277653069909, 6314.610785627155, 6330.68346582669, 6331.746730130789, 6046.107437521229, 6138.785142520035, 6155.661438620874, 6157.384261573851, 6178.876422471525, 6763.594184348905, 6782.898256526156, 6822.351876571718, 6869.7595584154815, 6885.798103881719, 5382.407935127133, 5424.755885985101, 5445.346558413015, 5446.754896029688, 5468.644538105011, 6783.6269005204695, 6887.586744795329, 6943.479239057091, 7013.579185248901, 7212.495125341228, 5509.834704958857, 5569.136783798518, 5605.5795815313195, 5622.7750889963945, 5640.915386269209, 5645.5483561314995, 5669.376368599451, 5685.6304340218585, 5696.11710184922, 5754.625949313281, 6093.451589356979, 6111.419415155642, 6127.877442451126, 6149.2620885494025, 6191.690415479208, 6781.355771201728, 6858.622775747382, 6889.827754306182, 6895.827604309832, 6917.204521479994, 1956.5402130499322, 1988.4730546405215, 1995.8303544198616, 2000.5898790747863, 2012.8188463038398, 1340.7117469744053, 1440.6686462113562, 1475.3803402570945, 1481.2371628611274, 1491.597268737435, 5857.916285487198, 5864.237649105674, 5868.882622347614, 5886.911696324559, 6011.677430965181, 6763.458923286125, 6891.27641597625, 6915.792526224222, 6927.549709268881, 6964.129483814523, 5766.4142360850265, 5832.825015249549, 5840.649561026352, 5892.5285977122585, 5906.0, 3612.909677258037, 3631.1662610381586, 3639.79981101042, 3642.6357364263363, 3645.289168612095, 6616.206055273398, 6656.633885136364, 6661.4004739573675, 6700.329967003261, 6715.294894036154, 6951.270116637781, 7020.192677842011, 7031.964840175767, 7077.5137221183095, 7117.145942486831, 6661.866762664719, 6690.565113267622, 6692.230393504809, 6711.194656641137, 6760.391564759196, 6805.950459476603, 6813.89779153323, 6873.1064961554675, 6892.21117199548, 6922.7923162305215, 6729.259781423942, 6745.898811517929, 6784.21981472137, 6804.557703749439, 6853.246142924175, 6581.177352830896, 6604.900926486011, 6624.8012559623285, 6631.1379520662, 6723.159605049369, 5501.559875210054, 5554.694491802989, 5578.163275508706, 5583.330000399978, 5589.77640894376, 6524.771632992823, 6776.593404395752, 6839.487038472234, 6853.623050732058, 6861.828454288483, 6391.616503009648, 6581.440577550962, 6636.767713130017, 6688.765893193715, 6727.0, 6571.704273307617, 6595.208574971034, 6635.2037755469655, 6675.0, 6722.193336799615, 6320.462760665395, 6511.772087976898, 6577.473802095917, 6588.484648530961, 6613.834654133685, 6631.635260060743, 6681.665916704048, 6702.899456508254, 6705.4635629150525, 6768.323167683192, 6410.775622853364, 6437.67811609427, 6438.3883531064475, 6518.934810651833, 6559.376859198559], "value": 5284.402590296105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [882.7440042387707, 892.6563273139839, 893.2407453664384, 908.9681861134861, 911.749268951038, 17874.73187902181, 18871.433856984277, 18881.055947202636, 18900.0, 21304.14783408662, 20092.799072009275, 20464.18143274271, 20683.276085337016, 20714.378568642926, 20848.583028339424, 933.5752232734089, 962.6053318139565, 962.6678795815433, 963.7879666473365, 964.0070727151011, 934.6822080492631, 968.3318510227955, 973.3138137612966, 974.4786539201529, 974.6783561424749, 995.8406654935212, 998.6604554451485, 1003.0521157506159, 1003.2325271167526, 1004.2618675273668, 994.5723338964242, 995.1690338567341, 995.372915063509, 996.4967691315911, 997.2022382094368, 735.5807189901758, 735.6321839080459, 738.7451329291385, 738.75621045055, 739.7965559471135, 17122.82877171237, 17273.740893886603, 17301.394451194275, 17302.22140003703, 19396.806031939643, 19505.414837554898, 19602.313919012908, 19963.600727985504, 19969.0, 20200.595988080302, 15690.9215453922, 15900.52298431049, 16145.0, 16170.919145404197, 16176.75734863978, 3693.686036686885, 3756.586775454698, 3758.605346438612, 3758.68051215647, 3761.811909404546, 119083.4045829774, 120228.0, 121249.39375303155, 122168.38915805453, 124566.37716811449, 15300.617484562752, 15579.221038948008, 15607.921960390126, 15625.921870390575, 15838.445654402041, 29456.705432945826, 29536.704632953395, 29924.0, 29944.101676949726, 30073.548896766566, 7264.200937896827, 8475.067742548315, 8794.824103518022, 8960.372773905894, 8966.775830604156, 3935.193285376491, 4000.7599544027453, 4011.117554138085, 4033.4554835097474, 4048.919021619551, 4989.476105008995, 5054.69671819692, 5067.822626208139, 5112.463191364927, 5113.1052065888325, 1030.6289735695143, 1036.44550165661, 1050.0601961244795, 1051.363924825485, 1052.9315594486334, 678.9762358317436, 686.8591938652564, 689.8792711275506, 702.3538344722867, 702.8875379939287, 11076.0, 12100.636980890587, 12132.878671213351, 12659.303738294298, 13029.609111726664, 674.5446823394287, 676.3878689785752, 679.1307126877605, 682.160942041292, 683.8700646877078, 5640.717964101778, 5741.827745167651, 5751.4536119068625, 5870.972579798604, 5884.470397664145, 111826.0, 112457.43771281092, 112967.43516282366, 114904.0, 116187.0, 99850.0, 102725.91822245345, 102928.0, 107180.46409768057, 108045.45977270063, 87301.56349218213, 87472.5626371864, 88884.555577223, 91620.0, 92035.53982230046, 10901.727456813325, 11755.88244117548, 11904.523819047141, 12347.506099755909, 13082.084254102121, 41657.37513937233, 42398.152036959706, 42653.36019959765, 42808.0, 44039.0, 70468.64765676139, 71372.28627713864, 72983.0, 73080.04764681574, 73615.0, 16933.915330423268, 17187.656246874743, 17220.311187552357, 17555.56111097233, 17599.560010999314, 21520.139194432046, 22066.668999965335, 22336.21823236179, 22725.204617838295, 22740.317790466313, 19139.808601913803, 19292.71060934059, 19421.126049327533, 19508.60982780308, 19594.216231350587, 19693.0, 19860.00699965025, 20527.28154514585, 20596.27913023037, 20635.4841128973, 17386.9130654351, 17477.475675729747, 18786.342478013205, 19667.409977700692, 19683.507912302306, 13425.0, 13626.523071692443, 13988.300584970508, 14327.57017289483, 14418.567442976728, 14184.290785460893, 14376.712465750417, 14779.778303325244, 14919.85080149228, 15124.092554446768], "value": 9137.513411840215, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1487.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 100.0, 108.0, 109.0, 113.0, 108.0, 98.0, 108.0, 110.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 141.0, 170.0, 117.0, 143.0, 141.0, 121.0, 174.0, 122.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 164.0, 163.0, 164.0, 165.0, 171.0, 169.0, 161.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 83.0, 73.0, 174.0, 75.0, 77.0, 74.0, 76.0, 156.0, 76.0], "value": 93.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 130.0, 79.0, 80.0, 79.0, 122.0, 79.0, 79.0, 125.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 115.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 148.0, 150.0, 148.0, 148.0, 148.0, 147.0, 149.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 99.0, 100.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 65.0, 52.0, 53.0, 54.0, 53.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 39.0, 38.0, 38.0, 38.0, 38.0, 39.0, 39.0, 38.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 74.0, 77.0, 74.0, 74.0, 76.0, 76.0, 76.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 124.0, 133.0, 125.0, 125.0, 125.0, 118.0, 121.0, 120.0, 151.0], "value": 127.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 175.0, 174.0, 170.0, 171.0, 172.0, 178.0, 170.0, 179.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 67.0, 68.0, 64.0, 64.0, 65.0, 63.0, 62.0], "value": 65.0, "unit": "score"}]}]} 03:04:45 INFO - Return code: 0 03:04:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:04:45 INFO - # TBPL SUCCESS # 03:04:45 INFO - Running post-action listener: _package_coverage_data 03:04:45 INFO - Running post-action listener: _resource_record_post_action 03:04:45 INFO - [mozharness: 2017-09-02 10:04:45.875892Z] Finished run-tests step (success) 03:04:45 INFO - Running post-run listener: _resource_record_post_run 03:04:45 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 390451200; Read time: 28; Write time: 282896 03:04:45 INFO - TinderboxPrint: CPU usage
13.1% 03:04:45 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 03:04:45 INFO - TinderboxPrint: I/O write bytes / time
390,451,200 / 282,896 03:04:45 INFO - TinderboxPrint: CPU idle
8,234.8 (86.9%) 03:04:45 INFO - TinderboxPrint: CPU user
1,193.1 (12.6%) 03:04:45 INFO - TinderboxPrint: Swap in / out
0 / 0 03:04:45 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106225664; Read time: 0; Write time: 115240 03:04:45 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:04:45 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 284225536; Read time: 28; Write time: 167656 03:04:46 INFO - Running post-run listener: _upload_blobber_files 03:04:46 INFO - Blob upload gear active. 03:04:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:04:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:04:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:04:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:04:46 INFO - (blobuploader) - INFO - Open directory for files ... 03:04:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:04:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:04:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:48 INFO - (blobuploader) - INFO - Done attempting. 03:04:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:04:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:04:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:54 INFO - (blobuploader) - INFO - Done attempting. 03:04:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:04:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:04:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:55 INFO - (blobuploader) - INFO - Done attempting. 03:04:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:04:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:04:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:56 INFO - (blobuploader) - INFO - Done attempting. 03:04:56 INFO - (blobuploader) - INFO - Iteration through files over. 03:04:56 INFO - Return code: 0 03:04:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:04:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:04:56 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68d39e373f586b501ceb82c16cc6de1e2e9d0df0219d639d44c794fac2e39985e022ed4cd0dedf2f154f925344a1221b75ab7b965ac90b2b6280b849100fe915", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4524da6ebbf5f90ed6f570d1d5ec7be78c07b1c3b0ed7116024156221c12c3034d2841107e397f0a34ebe669909d4f35c746ff69b5ad859312253b36db32d6f3", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e381140e40349244e0ac09cff8e748c51cb0f05c3d89c046f09da1be05be87ab05c13008edb89e72d3bb5fca8f9cb2659f5625ecd84c2b3885691140058cb2b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c58447de255b488a058d732224b482b1c6c158e27d0892f6619e6ccea813489a8f2dbf2d567c4ce8a29ad0df10d4361da146c3aa570162d5d75637e8ecd27eaa"} 03:04:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:04:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:04:56 INFO - Contents: 03:04:56 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68d39e373f586b501ceb82c16cc6de1e2e9d0df0219d639d44c794fac2e39985e022ed4cd0dedf2f154f925344a1221b75ab7b965ac90b2b6280b849100fe915", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4524da6ebbf5f90ed6f570d1d5ec7be78c07b1c3b0ed7116024156221c12c3034d2841107e397f0a34ebe669909d4f35c746ff69b5ad859312253b36db32d6f3", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e381140e40349244e0ac09cff8e748c51cb0f05c3d89c046f09da1be05be87ab05c13008edb89e72d3bb5fca8f9cb2659f5625ecd84c2b3885691140058cb2b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c58447de255b488a058d732224b482b1c6c158e27d0892f6619e6ccea813489a8f2dbf2d567c4ce8a29ad0df10d4361da146c3aa570162d5d75637e8ecd27eaa"} 03:04:56 INFO - Running post-run listener: copy_logs_to_upload_dir 03:04:56 INFO - Copying logs to upload dir... 03:04:56 INFO - mkdir: /builds/slave/test/build/upload/logs 03:04:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.010976 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-02 03:04:56.869467) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 03:04:56.874066) ========= 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-1504345444.778711-834712415 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-Non-PGO/sha512/68d39e373f586b501ceb82c16cc6de1e2e9d0df0219d639d44c794fac2e39985e022ed4cd0dedf2f154f925344a1221b75ab7b965ac90b2b6280b849100fe915", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4524da6ebbf5f90ed6f570d1d5ec7be78c07b1c3b0ed7116024156221c12c3034d2841107e397f0a34ebe669909d4f35c746ff69b5ad859312253b36db32d6f3", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e381140e40349244e0ac09cff8e748c51cb0f05c3d89c046f09da1be05be87ab05c13008edb89e72d3bb5fca8f9cb2659f5625ecd84c2b3885691140058cb2b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c58447de255b488a058d732224b482b1c6c158e27d0892f6619e6ccea813489a8f2dbf2d567c4ce8a29ad0df10d4361da146c3aa570162d5d75637e8ecd27eaa"} build_url:https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/ftQy0px5TK6bx82a3KX9nw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68d39e373f586b501ceb82c16cc6de1e2e9d0df0219d639d44c794fac2e39985e022ed4cd0dedf2f154f925344a1221b75ab7b965ac90b2b6280b849100fe915", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4524da6ebbf5f90ed6f570d1d5ec7be78c07b1c3b0ed7116024156221c12c3034d2841107e397f0a34ebe669909d4f35c746ff69b5ad859312253b36db32d6f3", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e381140e40349244e0ac09cff8e748c51cb0f05c3d89c046f09da1be05be87ab05c13008edb89e72d3bb5fca8f9cb2659f5625ecd84c2b3885691140058cb2b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c58447de255b488a058d732224b482b1c6c158e27d0892f6619e6ccea813489a8f2dbf2d567c4ce8a29ad0df10d4361da146c3aa570162d5d75637e8ecd27eaa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 03:04:56.901620) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 03:04:56.901913) ========= 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-1504345444.778711-834712415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004673 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 03:04:56.985493) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 03:04:56.985785) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 03:04:56.986066) ========= ========= Total master_lag: 0.27 =========