builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1504652462.25 results: success (0) revision: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:02.253833) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:02.254343) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:02.254644) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:02.272093) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:02.272558) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-05 16:01:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2017-09-05 16:01:02 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.163169 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:02.453463) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:02.453823) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016914 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:02.503896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:02.504249) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 --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 f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 --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=03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-05 16:01:02,418 truncating revision to first 12 chars 2017-09-05 16:01:02,418 Setting DEBUG logging. 2017-09-05 16:01:02,418 attempt 1/10 2017-09-05 16:01:02,418 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f64e2b4dcf5e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-05 16:01:02,756 unpacking tar archive at: mozilla-central-f64e2b4dcf5e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.683843 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:03.212305) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:03.212873) ========= script_repo_revision: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:03.213342) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:03.213666) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 16:01:03.227453) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-05 16:01:03.227930) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:01:03 INFO - MultiFileLogger online at 20170905 16:01:03 in /builds/slave/test 16:01:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:01:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:01:03 INFO - {'append_to_log': False, 16:01:03 INFO - 'base_work_dir': '/builds/slave/test', 16:01:03 INFO - 'blob_upload_branch': 'Firefox', 16:01:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:01:03 INFO - 'branch': 'Firefox', 16:01:03 INFO - 'buildbot_json_path': 'buildprops.json', 16:01:03 INFO - 'code_coverage': False, 16:01:03 INFO - 'config_files': ('talos/linux_config.py',), 16:01:03 INFO - 'default_actions': ('clobber', 16:01:03 INFO - 'read-buildbot-config', 16:01:03 INFO - 'download-and-extract', 16:01:03 INFO - 'populate-webroot', 16:01:03 INFO - 'create-virtualenv', 16:01:03 INFO - 'install', 16:01:03 INFO - 'setup-mitmproxy', 16:01:03 INFO - 'run-tests'), 16:01:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:01:03 INFO - 'disable_ccov_upload': False, 16:01:03 INFO - 'disable_stylo': False, 16:01:03 INFO - 'download_minidump_stackwalk': True, 16:01:03 INFO - 'download_symbols': 'ondemand', 16:01:03 INFO - 'enable_stylo': False, 16:01:03 INFO - 'enable_webrender': False, 16:01:03 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:01:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:01:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:01:03 INFO - 'gecko_profile': False, 16:01:03 INFO - 'gecko_profile_interval': 0, 16:01:03 INFO - 'installer_path': 'installer.exe', 16:01:03 INFO - 'log_level': 'info', 16:01:03 INFO - 'log_name': 'talos', 16:01:03 INFO - 'log_to_console': True, 16:01:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:01:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:01:03 INFO - 'opt_config_files': (), 16:01:03 INFO - 'pip_index': False, 16:01:03 INFO - 'suite': 'dromaeojs-e10s', 16:01:03 INFO - 'system_bits': '32', 16:01:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:01:03 INFO - 'title': 'talos-linux64-ix-047', 16:01:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:01:03 INFO - 'use_talos_json': True, 16:01:03 INFO - 'verify': 'False', 16:01:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:01:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:01:03 INFO - 'work_dir': 'build'} 16:01:03 INFO - [mozharness: 2017-09-05 23:01:03.282551Z] Running clobber step. 16:01:03 INFO - Running pre-action listener: _resource_record_pre_action 16:01:03 INFO - Running main action method: clobber 16:01:03 INFO - rmtree: /builds/slave/test/build 16:01:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:01:04 INFO - Running post-action listener: _resource_record_post_action 16:01:04 INFO - [mozharness: 2017-09-05 23:01:04.292039Z] Finished clobber step (success) 16:01:04 INFO - [mozharness: 2017-09-05 23:01:04.292135Z] Running read-buildbot-config step. 16:01:04 INFO - Running pre-action listener: _resource_record_pre_action 16:01:04 INFO - Running main action method: read_buildbot_config 16:01:04 INFO - Using buildbot properties: 16:01:04 INFO - { 16:01:04 INFO - "project": "", 16:01:04 INFO - "product": "firefox", 16:01:04 INFO - "who": "archaeopteryx@coole-files.de", 16:01:04 INFO - "installer_path": "public/build/target.tar.bz2", 16:01:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 16:01:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:01:04 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 16:01:04 INFO - "stage_platform": "linux64-stylo", 16:01:04 INFO - "basedir": "/builds/slave/test", 16:01:04 INFO - "buildnumber": 300, 16:01:04 INFO - "platform": "ubuntu64_hw_stylo", 16:01:04 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:01:04 INFO - "slavebuilddir": "test", 16:01:04 INFO - "taskId": "a4cdjo35TcmiSDX2FAmVRg", 16:01:04 INFO - "branch": "mozilla-central", 16:01:04 INFO - "script_repo_revision": "production", 16:01:04 INFO - "revision": "f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4", 16:01:04 INFO - "slavename": "talos-linux64-ix-047", 16:01:04 INFO - "repo_path": "mozilla-central" 16:01:04 INFO - } 16:01:04 INFO - Finding installer, test and symbols from parent task. 16:01:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a4cdjo35TcmiSDX2FAmVRg'}, attempt #1 16:01:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a4cdjo35TcmiSDX2FAmVRg'}, attempt #1 16:01:05 INFO - Task dependencies: VQZMjgW9QkOpdRucLZyMvw 16:01:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw'}, attempt #1 16:01:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:01:05 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json 16:01:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip 16:01:05 INFO - Running post-action listener: _resource_record_post_action 16:01:05 INFO - [mozharness: 2017-09-05 23:01:05.857575Z] Finished read-buildbot-config step (success) 16:01:05 INFO - [mozharness: 2017-09-05 23:01:05.857795Z] Running download-and-extract step. 16:01:05 INFO - Running pre-action listener: _resource_record_pre_action 16:01:05 INFO - Running main action method: download_and_extract 16:01:05 INFO - mkdir: /builds/slave/test/build/tests 16:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:05 INFO - trying https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json 16:01:05 INFO - Downloading https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:01:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:01:06 INFO - Downloaded 989 bytes. 16:01:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:01:06 INFO - Using the following test package requirements: 16:01:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:01:06 INFO - u'common': [u'target.common.tests.zip'], 16:01:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:01:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:01:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:01:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:01:06 INFO - u'mozbase': [u'target.common.tests.zip'], 16:01:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:01:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:01:06 INFO - u'web-platform': [u'target.common.tests.zip', 16:01:06 INFO - u'target.web-platform.tests.tar.gz'], 16:01:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:01:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:01:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip 16:01:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:01:06 INFO - Fetch https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip into memory 16:01:07 INFO - Content-Length response header: 55019892 16:01:07 INFO - Bytes received: 55019892 16:01:18 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:01:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip 16:01:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:01:18 INFO - Fetch https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip into memory 16:01:19 INFO - Content-Length response header: 55019892 16:01:19 INFO - Bytes received: 55019892 16:01:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.talos.tests.zip 16:01:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:01:32 INFO - Fetch https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.talos.tests.zip into memory 16:01:32 INFO - Content-Length response header: 14496654 16:01:32 INFO - Bytes received: 14496654 16:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:33 INFO - trying https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:01:33 INFO - Downloading https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:01:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:01:34 INFO - Downloaded 62759133 bytes. 16:01:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:01:34 INFO - mkdir: /builds/slave/test/properties 16:01:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:34 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:34 INFO - Contents: 16:01:34 INFO - build_url:https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:01:34 INFO - Running post-action listener: _resource_record_post_action 16:01:34 INFO - Running post-action listener: find_tests_for_verification 16:01:34 INFO - Running post-action listener: set_extra_try_arguments 16:01:34 INFO - [mozharness: 2017-09-05 23:01:34.281058Z] Finished download-and-extract step (success) 16:01:34 INFO - [mozharness: 2017-09-05 23:01:34.281155Z] Running populate-webroot step. 16:01:34 INFO - Running pre-action listener: _resource_record_pre_action 16:01:34 INFO - Running main action method: populate_webroot 16:01:34 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:01:34 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:01:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:01:34 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:01:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:34 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'tests': ['damp', 'tps']}, 16:01:34 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['damp', 'tps']}, 16:01:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:01:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:01:34 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:01:34 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:01:34 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:01:34 INFO - 'other-e10s': {'tests': ['a11yr', 16:01:34 INFO - 'ts_paint', 16:01:34 INFO - 'tpaint', 16:01:34 INFO - 'sessionrestore', 16:01:34 INFO - 'sessionrestore_many_windows', 16:01:34 INFO - 'sessionrestore_no_auto_restore', 16:01:34 INFO - 'tabpaint']}, 16:01:34 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['a11yr', 16:01:34 INFO - 'ts_paint', 16:01:34 INFO - 'tpaint', 16:01:34 INFO - 'sessionrestore', 16:01:34 INFO - 'sessionrestore_many_windows', 16:01:34 INFO - 'sessionrestore_no_auto_restore', 16:01:34 INFO - 'tabpaint']}, 16:01:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:01:34 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:01:34 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['bloom_basic_singleton']}, 16:01:34 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['bloom_basic']}, 16:01:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:01:34 INFO - 'tsvgr_opacity', 16:01:34 INFO - 'tart', 16:01:34 INFO - 'tscrollx', 16:01:34 INFO - 'tsvg_static']}, 16:01:34 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['tsvgx', 16:01:34 INFO - 'tsvgr_opacity', 16:01:34 INFO - 'tart', 16:01:34 INFO - 'tscrollx', 16:01:34 INFO - 'tsvg_static']}, 16:01:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:01:34 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['tp5o']}, 16:01:34 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:01:34 INFO - 'talos_options': ['--mitmproxy', 16:01:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:01:34 INFO - '--firstNonBlankPaint'], 16:01:34 INFO - 'tests': ['tp6_google', 16:01:34 INFO - 'tp6_youtube', 16:01:34 INFO - 'tp6_amazon', 16:01:34 INFO - 'tp6_facebook']}, 16:01:34 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo', 16:01:34 INFO - '--mitmproxy', 16:01:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:01:34 INFO - '--firstNonBlankPaint'], 16:01:34 INFO - 'tests': ['tp6_google', 16:01:34 INFO - 'tp6_youtube', 16:01:34 INFO - 'tp6_amazon', 16:01:34 INFO - 'tp6_facebook']}, 16:01:34 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo', 16:01:34 INFO - '--stylo-threads=1', 16:01:34 INFO - '--mitmproxy', 16:01:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:01:34 INFO - '--firstNonBlankPaint'], 16:01:34 INFO - 'tests': ['tp6_google', 16:01:34 INFO - 'tp6_youtube', 16:01:34 INFO - 'tp6_amazon', 16:01:34 INFO - 'tp6_facebook']}, 16:01:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--xperf_path', 16:01:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:34 INFO - 'tests': ['tp5n']}, 16:01:34 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo', 16:01:34 INFO - '--xperf_path', 16:01:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:34 INFO - 'tests': ['tp5n']}}} 16:01:34 INFO - Running post-action listener: _resource_record_post_action 16:01:34 INFO - [mozharness: 2017-09-05 23:01:34.295371Z] Finished populate-webroot step (success) 16:01:34 INFO - [mozharness: 2017-09-05 23:01:34.295459Z] Running create-virtualenv step. 16:01:34 INFO - Running pre-action listener: _resource_record_pre_action 16:01:34 INFO - Running main action method: create_virtualenv 16:01:34 INFO - Creating virtualenv /builds/slave/test/build/venv 16:01:34 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:01:34 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:01:34 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:01:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:01:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:01:34 INFO - Installing distribute.............................................................................................................................................................................................done. 16:01:35 INFO - Installing pip...............done. 16:01:35 INFO - Return code: 0 16:01:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:01:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:01:35 INFO - Reading from file tmpfile_stdout 16:01:35 INFO - Output received: 16:01:35 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:01:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01: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')]} 16:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', '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 16:01:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:01:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:01:35 INFO - Using env: {'DISPLAY': ':0', 16:01:35 INFO - 'HOME': '/home/cltbld', 16:01:35 INFO - 'LANG': 'en_US.UTF-8', 16:01:35 INFO - 'LANGUAGE': 'en_US:en', 16:01:35 INFO - 'LOGNAME': 'cltbld', 16:01:35 INFO - 'MAIL': '/var/mail/cltbld', 16:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:35 INFO - 'MOZ_NO_REMOTE': '1', 16:01:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:35 INFO - 'NO_EM_RESTART': '1', 16:01:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:35 INFO - 'PWD': '/builds/slave/test', 16:01:35 INFO - 'SHELL': '/bin/bash', 16:01:35 INFO - 'SHLVL': '1', 16:01:35 INFO - 'TERM': 'linux', 16:01:35 INFO - 'TMOUT': '86400', 16:01:35 INFO - 'USER': 'cltbld', 16:01:35 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:35 INFO - '_': '/tools/buildbot/bin/python'} 16:01:36 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:01:36 INFO - Downloading/unpacking pip>=1.5 16:01:36 INFO - Running setup.py egg_info for package pip 16:01:36 INFO - warning: no files found matching 'pip/cacert.pem' 16:01:36 INFO - warning: no files found matching '*.html' under directory 'docs' 16:01:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:01:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:01:36 INFO - Installing collected packages: pip 16:01:36 INFO - Found existing installation: pip 1.1 16:01:36 INFO - Uninstalling pip: 16:01:36 INFO - Successfully uninstalled pip 16:01:36 INFO - Running setup.py install for pip 16:01:36 INFO - warning: no files found matching 'pip/cacert.pem' 16:01:36 INFO - warning: no files found matching '*.html' under directory 'docs' 16:01:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:01:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:01:36 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:01:36 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:01:36 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:01:36 INFO - Successfully installed pip 16:01:36 INFO - Cleaning up... 16:01:36 INFO - Return code: 0 16:01:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', '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 16:01:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:01:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:01:36 INFO - Using env: {'DISPLAY': ':0', 16:01:36 INFO - 'HOME': '/home/cltbld', 16:01:36 INFO - 'LANG': 'en_US.UTF-8', 16:01:36 INFO - 'LANGUAGE': 'en_US:en', 16:01:36 INFO - 'LOGNAME': 'cltbld', 16:01:36 INFO - 'MAIL': '/var/mail/cltbld', 16:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:36 INFO - 'MOZ_NO_REMOTE': '1', 16:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:36 INFO - 'NO_EM_RESTART': '1', 16:01:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:36 INFO - 'PWD': '/builds/slave/test', 16:01:36 INFO - 'SHELL': '/bin/bash', 16:01:36 INFO - 'SHLVL': '1', 16:01:36 INFO - 'TERM': 'linux', 16:01:36 INFO - 'TMOUT': '86400', 16:01:36 INFO - 'USER': 'cltbld', 16:01:36 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:36 INFO - '_': '/tools/buildbot/bin/python'} 16:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:36 INFO - Downloading/unpacking psutil>=3.1.1 16:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:01:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:37 INFO - Installing collected packages: psutil 16:01:37 INFO - Running setup.py install for psutil 16:01:37 INFO - building 'psutil._psutil_linux' extension 16:01:37 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 16:01:38 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 16:01:38 INFO - building 'psutil._psutil_posix' extension 16:01:38 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 16:01:38 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 16:01:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:38 INFO - Successfully installed psutil 16:01:38 INFO - Cleaning up... 16:01:38 INFO - Return code: 0 16:01:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', '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 16:01:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:01:38 INFO - Using env: {'DISPLAY': ':0', 16:01:38 INFO - 'HOME': '/home/cltbld', 16:01:38 INFO - 'LANG': 'en_US.UTF-8', 16:01:38 INFO - 'LANGUAGE': 'en_US:en', 16:01:38 INFO - 'LOGNAME': 'cltbld', 16:01:38 INFO - 'MAIL': '/var/mail/cltbld', 16:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:38 INFO - 'MOZ_NO_REMOTE': '1', 16:01:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:38 INFO - 'NO_EM_RESTART': '1', 16:01:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:38 INFO - 'PWD': '/builds/slave/test', 16:01:38 INFO - 'SHELL': '/bin/bash', 16:01:38 INFO - 'SHLVL': '1', 16:01:38 INFO - 'TERM': 'linux', 16:01:38 INFO - 'TMOUT': '86400', 16:01:38 INFO - 'USER': 'cltbld', 16:01:38 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:38 INFO - '_': '/tools/buildbot/bin/python'} 16:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:01:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:01:39 INFO - Installing collected packages: mozsystemmonitor 16:01:39 INFO - Running setup.py install for mozsystemmonitor 16:01:39 INFO - Successfully installed mozsystemmonitor 16:01:39 INFO - Cleaning up... 16:01:39 INFO - Return code: 0 16:01:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01: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')]} 16:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01: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', '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 0x1c101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', '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 16:01: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:01: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 jsonschema==2.5.1 16:01:39 INFO - Using env: {'DISPLAY': ':0', 16:01:39 INFO - 'HOME': '/home/cltbld', 16:01:39 INFO - 'LANG': 'en_US.UTF-8', 16:01:39 INFO - 'LANGUAGE': 'en_US:en', 16:01:39 INFO - 'LOGNAME': 'cltbld', 16:01:39 INFO - 'MAIL': '/var/mail/cltbld', 16:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:39 INFO - 'MOZ_NO_REMOTE': '1', 16:01:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:39 INFO - 'NO_EM_RESTART': '1', 16:01:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:39 INFO - 'PWD': '/builds/slave/test', 16:01:39 INFO - 'SHELL': '/bin/bash', 16:01:39 INFO - 'SHLVL': '1', 16:01:39 INFO - 'TERM': 'linux', 16:01:39 INFO - 'TMOUT': '86400', 16:01:39 INFO - 'USER': 'cltbld', 16:01:39 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:39 INFO - '_': '/tools/buildbot/bin/python'} 16:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:40 INFO - Downloading/unpacking jsonschema==2.5.1 16:01:40 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 16:01:40 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 16:01:40 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 16:01:40 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 16:01:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:01:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:01: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 16:01: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 16:01: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 16:01: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 16:01:41 INFO - Downloading functools32-3.2.3-2.tar.gz 16:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:01:41 INFO - warning: no files found matching '*.txt' 16:01:41 INFO - no previously-included directories found matching 'build' 16:01:41 INFO - no previously-included directories found matching 'dist' 16:01:41 INFO - no previously-included directories found matching '.git*' 16:01:41 INFO - Installing collected packages: jsonschema, functools32 16:01:41 INFO - Running setup.py install for functools32 16:01:41 INFO - warning: no files found matching '*.txt' 16:01:41 INFO - no previously-included directories found matching 'build' 16:01:41 INFO - no previously-included directories found matching 'dist' 16:01:41 INFO - no previously-included directories found matching '.git*' 16:01:41 INFO - Successfully installed jsonschema functools32 16:01:41 INFO - Cleaning up... 16:01:41 INFO - Return code: 0 16:01:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01: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')]} 16:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01: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', '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 0x1c101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', '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 16:01: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:01: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 functools32==3.2.3-2 16:01:41 INFO - Using env: {'DISPLAY': ':0', 16:01:41 INFO - 'HOME': '/home/cltbld', 16:01:41 INFO - 'LANG': 'en_US.UTF-8', 16:01:41 INFO - 'LANGUAGE': 'en_US:en', 16:01:41 INFO - 'LOGNAME': 'cltbld', 16:01:41 INFO - 'MAIL': '/var/mail/cltbld', 16:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:41 INFO - 'MOZ_NO_REMOTE': '1', 16:01:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:41 INFO - 'NO_EM_RESTART': '1', 16:01:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:41 INFO - 'PWD': '/builds/slave/test', 16:01:41 INFO - 'SHELL': '/bin/bash', 16:01:41 INFO - 'SHLVL': '1', 16:01:41 INFO - 'TERM': 'linux', 16:01:41 INFO - 'TMOUT': '86400', 16:01:41 INFO - 'USER': 'cltbld', 16:01:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:41 INFO - '_': '/tools/buildbot/bin/python'} 16:01:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:01:41 INFO - Cleaning up... 16:01:41 INFO - Return code: 0 16:01:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01: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')]} 16:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01: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', '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 0x1c101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', '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 16:01: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:01: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 blobuploader==1.2.4 16:01:41 INFO - Using env: {'DISPLAY': ':0', 16:01:41 INFO - 'HOME': '/home/cltbld', 16:01:41 INFO - 'LANG': 'en_US.UTF-8', 16:01:41 INFO - 'LANGUAGE': 'en_US:en', 16:01:41 INFO - 'LOGNAME': 'cltbld', 16:01:41 INFO - 'MAIL': '/var/mail/cltbld', 16:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:41 INFO - 'MOZ_NO_REMOTE': '1', 16:01:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:41 INFO - 'NO_EM_RESTART': '1', 16:01:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:41 INFO - 'PWD': '/builds/slave/test', 16:01:41 INFO - 'SHELL': '/bin/bash', 16:01:41 INFO - 'SHLVL': '1', 16:01:41 INFO - 'TERM': 'linux', 16:01:41 INFO - 'TMOUT': '86400', 16:01:41 INFO - 'USER': 'cltbld', 16:01:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:41 INFO - '_': '/tools/buildbot/bin/python'} 16:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:42 INFO - Downloading/unpacking blobuploader==1.2.4 16:01: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 16:01: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 16:01: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 16:01: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 16:01:43 INFO - Downloading blobuploader-1.2.4.tar.gz 16:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:01:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:01: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 16:01: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 16:01: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 16:01: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 16:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:01:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:01: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 16:01: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 16:01: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 16:01: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 16:01:43 INFO - Downloading docopt-0.6.1.tar.gz 16:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:01:43 INFO - Installing collected packages: blobuploader, requests, docopt 16:01:43 INFO - Running setup.py install for blobuploader 16:01:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:01:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:01:43 INFO - Running setup.py install for requests 16:01:44 INFO - Running setup.py install for docopt 16:01:44 INFO - Successfully installed blobuploader requests docopt 16:01:44 INFO - Cleaning up... 16:01:44 INFO - Return code: 0 16:01:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01: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')]} 16:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:44 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 0x1c101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', '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 16:01:44 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 16:01:44 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 16:01:44 INFO - Using env: {'DISPLAY': ':0', 16:01:44 INFO - 'HOME': '/home/cltbld', 16:01:44 INFO - 'LANG': 'en_US.UTF-8', 16:01:44 INFO - 'LANGUAGE': 'en_US:en', 16:01:44 INFO - 'LOGNAME': 'cltbld', 16:01:44 INFO - 'MAIL': '/var/mail/cltbld', 16:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:44 INFO - 'MOZ_NO_REMOTE': '1', 16:01:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:44 INFO - 'NO_EM_RESTART': '1', 16:01:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:44 INFO - 'PWD': '/builds/slave/test', 16:01:44 INFO - 'SHELL': '/bin/bash', 16:01:44 INFO - 'SHLVL': '1', 16:01:44 INFO - 'TERM': 'linux', 16:01:44 INFO - 'TMOUT': '86400', 16:01:44 INFO - 'USER': 'cltbld', 16:01:44 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:44 INFO - '_': '/tools/buildbot/bin/python'} 16:01:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:44 INFO - Running setup.py (path:/tmp/pip-qS9hpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:44 INFO - Running setup.py (path:/tmp/pip-UnwF7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:44 INFO - Running setup.py (path:/tmp/pip-MTq0Lk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:44 INFO - Running setup.py (path:/tmp/pip-WvIMyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:44 INFO - Running setup.py (path:/tmp/pip-bta9n_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:44 INFO - Running setup.py (path:/tmp/pip-PU3Ib0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:44 INFO - Running setup.py (path:/tmp/pip-ebX7P8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:45 INFO - Running setup.py (path:/tmp/pip-y47clJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:45 INFO - Running setup.py (path:/tmp/pip-k7jbj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:45 INFO - Running setup.py (path:/tmp/pip-VC7CAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:45 INFO - Running setup.py (path:/tmp/pip-JvizZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:45 INFO - Running setup.py (path:/tmp/pip-7o6awM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:45 INFO - Running setup.py (path:/tmp/pip-2njp5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:45 INFO - Running setup.py (path:/tmp/pip-PYS75d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:45 INFO - Running setup.py (path:/tmp/pip-i5Fvup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:45 INFO - Running setup.py (path:/tmp/pip-RamlRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:45 INFO - Running setup.py (path:/tmp/pip-OqrVvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:01:45 INFO - Running setup.py install for manifestparser 16:01:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:01:45 INFO - Running setup.py install for mozcrash 16:01:45 INFO - Running setup.py install for mozdebug 16:01:46 INFO - Running setup.py install for mozdevice 16:01:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for mozfile 16:01:46 INFO - Running setup.py install for mozhttpd 16:01:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for mozinfo 16:01:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for mozInstall 16:01:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for mozleak 16:01:46 INFO - Running setup.py install for mozlog 16:01:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for moznetwork 16:01:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for mozprocess 16:01:46 INFO - Running setup.py install for mozprofile 16:01:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:01:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:01:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:01:47 INFO - Running setup.py install for mozrunner 16:01:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:01:47 INFO - Running setup.py install for mozscreenshot 16:01:47 INFO - Running setup.py install for moztest 16:01:47 INFO - Running setup.py install for mozversion 16:01:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:01:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:01:47 INFO - Cleaning up... 16:01:47 INFO - Return code: 0 16:01:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01: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')]} 16:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:47 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 0x1c101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', '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 16:01:47 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 16:01:47 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 16:01:47 INFO - Using env: {'DISPLAY': ':0', 16:01:47 INFO - 'HOME': '/home/cltbld', 16:01:47 INFO - 'LANG': 'en_US.UTF-8', 16:01:47 INFO - 'LANGUAGE': 'en_US:en', 16:01:47 INFO - 'LOGNAME': 'cltbld', 16:01:47 INFO - 'MAIL': '/var/mail/cltbld', 16:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:47 INFO - 'MOZ_NO_REMOTE': '1', 16:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:47 INFO - 'NO_EM_RESTART': '1', 16:01:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:47 INFO - 'PWD': '/builds/slave/test', 16:01:47 INFO - 'SHELL': '/bin/bash', 16:01:47 INFO - 'SHLVL': '1', 16:01:47 INFO - 'TERM': 'linux', 16:01:47 INFO - 'TMOUT': '86400', 16:01:47 INFO - 'USER': 'cltbld', 16:01:47 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:47 INFO - '_': '/tools/buildbot/bin/python'} 16:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:47 INFO - Running setup.py (path:/tmp/pip-zb_4Hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:47 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)) 16:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:47 INFO - Running setup.py (path:/tmp/pip-vB6boe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:47 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)) 16:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:47 INFO - Running setup.py (path:/tmp/pip-07knOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:47 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)) 16:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:47 INFO - Running setup.py (path:/tmp/pip-ufsmlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:47 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)) 16:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:47 INFO - Running setup.py (path:/tmp/pip-0W2a4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:48 INFO - Running setup.py (path:/tmp/pip-tBOS8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:48 INFO - Running setup.py (path:/tmp/pip-CRgkmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:48 INFO - Running setup.py (path:/tmp/pip-sXhaKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:48 INFO - Running setup.py (path:/tmp/pip-s_X1ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:48 INFO - Running setup.py (path:/tmp/pip-QEPGcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:48 INFO - Running setup.py (path:/tmp/pip-wm83VH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:48 INFO - Running setup.py (path:/tmp/pip-mAtIpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:48 INFO - Running setup.py (path:/tmp/pip-T0YJGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:48 INFO - Running setup.py (path:/tmp/pip-dDEIKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:48 INFO - Running setup.py (path:/tmp/pip-NKp8fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:48 INFO - Running setup.py (path:/tmp/pip-oYBPdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:48 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)) 16:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:48 INFO - Running setup.py (path:/tmp/pip-KRabux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:50 INFO - Downloading blessings-1.6.tar.gz 16:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:01:50 INFO - Installing collected packages: blessings 16:01:50 INFO - Running setup.py install for blessings 16:01:50 INFO - Successfully installed blessings 16:01:50 INFO - Cleaning up... 16:01:50 INFO - Return code: 0 16:01:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:01:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:50 INFO - Reading from file tmpfile_stdout 16:01:50 INFO - Current package versions: 16:01:50 INFO - argparse == 1.2.1 16:01:50 INFO - blessings == 1.6 16:01:50 INFO - blobuploader == 1.2.4 16:01:50 INFO - docopt == 0.6.1 16:01:50 INFO - functools32 == 3.2.3-2 16:01:50 INFO - jsonschema == 2.5.1 16:01:50 INFO - manifestparser == 1.1 16:01:50 INFO - mozInstall == 1.13 16:01:50 INFO - mozcrash == 1.0 16:01:50 INFO - mozdebug == 0.1 16:01:50 INFO - mozdevice == 0.51 16:01:50 INFO - mozfile == 1.2 16:01:50 INFO - mozhttpd == 0.7 16:01:50 INFO - mozinfo == 0.10 16:01:50 INFO - mozleak == 0.1 16:01:50 INFO - mozlog == 3.5 16:01:50 INFO - moznetwork == 0.27 16:01:50 INFO - mozprocess == 0.25 16:01:50 INFO - mozprofile == 0.28 16:01:50 INFO - mozrunner == 6.13 16:01:50 INFO - mozscreenshot == 0.1 16:01:50 INFO - mozsystemmonitor == 0.3 16:01:50 INFO - moztest == 0.8 16:01:50 INFO - mozversion == 1.4 16:01:50 INFO - psutil == 3.1.1 16:01:50 INFO - requests == 1.2.3 16:01:50 INFO - wsgiref == 0.1.2 16:01:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01: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')]} 16:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:50 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 0x1c101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', '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 16:01:50 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 16:01:50 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 16:01:50 INFO - Using env: {'DISPLAY': ':0', 16:01:50 INFO - 'HOME': '/home/cltbld', 16:01:50 INFO - 'LANG': 'en_US.UTF-8', 16:01:50 INFO - 'LANGUAGE': 'en_US:en', 16:01:50 INFO - 'LOGNAME': 'cltbld', 16:01:50 INFO - 'MAIL': '/var/mail/cltbld', 16:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:50 INFO - 'MOZ_NO_REMOTE': '1', 16:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:50 INFO - 'NO_EM_RESTART': '1', 16:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:50 INFO - 'PWD': '/builds/slave/test', 16:01:50 INFO - 'SHELL': '/bin/bash', 16:01:50 INFO - 'SHLVL': '1', 16:01:50 INFO - 'TERM': 'linux', 16:01:50 INFO - 'TMOUT': '86400', 16:01:50 INFO - 'USER': 'cltbld', 16:01:50 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:50 INFO - '_': '/tools/buildbot/bin/python'} 16:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:50 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)) 16:01:50 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)) 16:01:50 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)) 16:01:50 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)) 16:01:50 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)) 16:01:50 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)) 16:01:50 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)) 16:01:50 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)) 16:01:50 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)) 16:01:50 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:01:50 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 16:01:50 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 16:01:50 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 16:01:50 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 16:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:01:51 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)) 16:01:51 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)) 16:01:51 INFO - Installing collected packages: simplejson 16:01:51 INFO - Running setup.py install for simplejson 16:01:51 INFO - building 'simplejson._speedups' extension 16:01:51 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 16:01:52 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 16:01:52 INFO - Successfully installed simplejson 16:01:52 INFO - Cleaning up... 16:01:52 INFO - Return code: 0 16:01:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f7ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', '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 16:01:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:01:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:01:52 INFO - Using env: {'DISPLAY': ':0', 16:01:52 INFO - 'HOME': '/home/cltbld', 16:01:52 INFO - 'LANG': 'en_US.UTF-8', 16:01:52 INFO - 'LANGUAGE': 'en_US:en', 16:01:52 INFO - 'LOGNAME': 'cltbld', 16:01:52 INFO - 'MAIL': '/var/mail/cltbld', 16:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:52 INFO - 'MOZ_NO_REMOTE': '1', 16:01:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:52 INFO - 'NO_EM_RESTART': '1', 16:01:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:52 INFO - 'PWD': '/builds/slave/test', 16:01:52 INFO - 'SHELL': '/bin/bash', 16:01:52 INFO - 'SHLVL': '1', 16:01:52 INFO - 'TERM': 'linux', 16:01:52 INFO - 'TMOUT': '86400', 16:01:52 INFO - 'USER': 'cltbld', 16:01:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:52 INFO - '_': '/tools/buildbot/bin/python'} 16:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:01:53 INFO - Cleaning up... 16:01:53 INFO - Return code: 0 16:01:53 INFO - Running post-action listener: _resource_record_post_action 16:01:53 INFO - Running post-action listener: _start_resource_monitoring 16:01:53 INFO - Starting resource monitoring. 16:01:53 INFO - [mozharness: 2017-09-05 23:01:53.174124Z] Finished create-virtualenv step (success) 16:01:53 INFO - [mozharness: 2017-09-05 23:01:53.174587Z] Running install step. 16:01:53 INFO - Running pre-action listener: _resource_record_pre_action 16:01:53 INFO - Running main action method: install 16:01:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:53 INFO - Reading from file tmpfile_stdout 16:01:53 INFO - Detecting whether we're running mozinstall >=1.0... 16:01:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:01:53 INFO - Reading from file tmpfile_stdout 16:01:53 INFO - Output received: 16:01:53 INFO - Usage: mozinstall [options] installer 16:01:53 INFO - Options: 16:01:53 INFO - -h, --help show this help message and exit 16:01:53 INFO - -d DEST, --destination=DEST 16:01:53 INFO - Directory to install application into. [default: 16:01:53 INFO - "/builds/slave/test"] 16:01:53 INFO - --app=APP Application being installed. [default: firefox] 16:01:53 INFO - mkdir: /builds/slave/test/build/application 16:01:53 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'] 16:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:02:10 INFO - Reading from file tmpfile_stdout 16:02:10 INFO - Output received: 16:02:10 INFO - /builds/slave/test/build/application/firefox/firefox 16:02:10 INFO - Running post-action listener: _resource_record_post_action 16:02:10 INFO - [mozharness: 2017-09-05 23:02:10.077969Z] Finished install step (success) 16:02:10 INFO - [mozharness: 2017-09-05 23:02:10.078217Z] Running setup-mitmproxy step. 16:02:10 INFO - Running pre-action listener: _resource_record_pre_action 16:02:10 INFO - Running main action method: setup_mitmproxy 16:02:10 INFO - Skipping: mitmproxy is not required 16:02:10 INFO - Running post-action listener: _resource_record_post_action 16:02:10 INFO - [mozharness: 2017-09-05 23:02:10.079322Z] Finished setup-mitmproxy step (success) 16:02:10 INFO - [mozharness: 2017-09-05 23:02:10.079537Z] Running run-tests step. 16:02:10 INFO - Running pre-action listener: _resource_record_pre_action 16:02:10 INFO - Running pre-action listener: _set_gcov_prefix 16:02:10 INFO - Running main action method: run_tests 16:02:10 WARNING - Try message not found. 16:02:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:02:10 INFO - Python 2.7.3 16:02:10 INFO - Return code: 0 16:02:10 INFO - grabbing minidump binary from tooltool 16:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:10 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 0x1f80090>, '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 0x1f80d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f7af90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:02:10 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 16:02:10 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 16:02:10 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 16:02:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:02:10 INFO - Return code: 0 16:02:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:02:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:02:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:02:10 INFO - ENV: RUST_BACKTRACE is now full 16:02:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:02:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:02:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 16:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 16:02:10 INFO - Using env: {'DISPLAY': ':0', 16:02:10 INFO - 'HOME': '/home/cltbld', 16:02:10 INFO - 'LANG': 'en_US.UTF-8', 16:02:10 INFO - 'LANGUAGE': 'en_US:en', 16:02:10 INFO - 'LOGNAME': 'cltbld', 16:02:10 INFO - 'MAIL': '/var/mail/cltbld', 16:02:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:10 INFO - 'MOZ_NO_REMOTE': '1', 16:02:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:10 INFO - 'NO_EM_RESTART': '1', 16:02:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:10 INFO - 'PWD': '/builds/slave/test', 16:02:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:10 INFO - 'RUST_BACKTRACE': 'full', 16:02:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:02:10 INFO - 'SHELL': '/bin/bash', 16:02:10 INFO - 'SHLVL': '1', 16:02:10 INFO - 'STYLO_FORCE_ENABLED': '1', 16:02:10 INFO - 'TERM': 'linux', 16:02:10 INFO - 'TMOUT': '86400', 16:02:10 INFO - 'USER': 'cltbld', 16:02:10 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:10 INFO - '_': '/tools/buildbot/bin/python'} 16:02:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 16:02:10 INFO - mozversion application_buildid: 20170905215634 16:02:10 INFO - mozversion application_changeset: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 16:02:10 INFO - mozversion application_display_name: Nightly 16:02:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:02:10 INFO - mozversion application_name: Firefox 16:02:10 INFO - mozversion application_remotingname: firefox 16:02:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:02:10 INFO - mozversion application_vendor: Mozilla 16:02:10 INFO - mozversion application_version: 57.0a1 16:02:10 INFO - mozversion platform_buildid: 20170905215634 16:02:10 INFO - mozversion platform_changeset: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 16:02:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:02:10 INFO - mozversion platform_version: 57.0a1 16:02:10 INFO - using testdate: 1504652530 16:02:10 INFO - actual date: 1504652530 16:02:10 INFO - starting webserver on 'localhost:34447' 16:02:10 INFO - SUITE-START | Running 2 tests 16:02:10 INFO - TEST-START | dromaeo_css 16:02:10 INFO - Initialising browser for dromaeo_css test... 16:02:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34447/getInfo.html -profile /tmp/tmp1PrAW8/profile 16:02:10 INFO - TEST-INFO | started process 9512 (/builds/slave/test/build/application/firefox/firefox http://localhost:34447/getInfo.html) 16:02:15 INFO - TEST-INFO | 9512: exit 0 16:02:15 INFO - Browser initialized. 16:02:15 INFO - Running cycle 1/1 for dromaeo_css test... 16:02:15 INFO - Using env: {'DISPLAY': ':0', 16:02:15 INFO - 'HOME': '/home/cltbld', 16:02:15 INFO - 'JSGC_DISABLE_POISONING': '1', 16:02:15 INFO - 'LANG': 'en_US.UTF-8', 16:02:15 INFO - 'LANGUAGE': 'en_US:en', 16:02:15 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:02:15 INFO - 'LOGNAME': 'cltbld', 16:02:15 INFO - 'MAIL': '/var/mail/cltbld', 16:02:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:02:15 INFO - 'MOZ_CRASHREPORTER': '1', 16:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:02:15 INFO - 'MOZ_NO_REMOTE': '1', 16:02:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:15 INFO - 'NO_EM_RESTART': '1', 16:02:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:15 INFO - 'PWD': '/builds/slave/test', 16:02:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:15 INFO - 'RUST_BACKTRACE': 'full', 16:02:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:02:15 INFO - 'SHELL': '/bin/bash', 16:02:15 INFO - 'SHLVL': '1', 16:02:15 INFO - 'STYLO_FORCE_ENABLED': '1', 16:02:15 INFO - 'TERM': 'linux', 16:02:15 INFO - 'TMOUT': '86400', 16:02:15 INFO - 'USER': 'cltbld', 16:02:15 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:15 INFO - '_': '/tools/buildbot/bin/python'} 16:02:15 INFO - TEST-INFO | started process 9709 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1PrAW8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:16 INFO - PID 9709 | 16:02:16 INFO - PID 9709 | (/builds/slave/test/build/application/firefox/firefox:9761): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:02:16 INFO - PID 9709 | 16:02:26 INFO - PID 9709 | RSS: Main: 173502464 16:02:26 INFO - PID 9709 | 16:05:23 INFO - PID 9709 | Cycle 1(1): loaded http://localhost:34447/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34447/tests/dromaeo/cssquery-ext.html) 16:05:24 INFO - PID 9709 | RSS: Main: 175828992 16:05:24 INFO - PID 9709 | 16:08:26 INFO - PID 9709 | Cycle 1(1): loaded http://localhost:34447/tests/dromaeo/cssquery-ext.html (next: http://localhost:34447/tests/dromaeo/cssquery-jquery.html) 16:08:26 INFO - PID 9709 | RSS: Main: 172838912 16:08:26 INFO - PID 9709 | 16:11:28 INFO - PID 9709 | Cycle 1(1): loaded http://localhost:34447/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34447/tests/dromaeo/cssquery-mootools.html) 16:11:29 INFO - PID 9709 | RSS: Main: 174878720 16:11:29 INFO - PID 9709 | 16:14:30 INFO - PID 9709 | Cycle 1(1): loaded http://localhost:34447/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34447/tests/dromaeo/cssquery-prototype.html) 16:14:31 INFO - PID 9709 | RSS: Main: 173121536 16:14:31 INFO - PID 9709 | 16:17:34 INFO - PID 9709 | Cycle 1(1): loaded http://localhost:34447/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34447/tests/dromaeo/cssquery-yui.html) 16:17:35 INFO - PID 9709 | RSS: Main: 173780992 16:17:35 INFO - PID 9709 | 16:20:36 INFO - PID 9709 | Cycle 1(1): loaded http://localhost:34447/tests/dromaeo/cssquery-yui.html (next: http://localhost:34447/tests/dromaeo/cssquery-dojo.html) 16:20:37 INFO - PID 9709 | RSS: Main: 175607808 16:20:37 INFO - PID 9709 | 16:20:37 INFO - PID 9709 | __start_tp_report 16:20:37 INFO - PID 9709 | _x_x_mozilla_page_load 16:20:37 INFO - PID 9709 | _x_x_mozilla_page_load_details 16:20:37 INFO - PID 9709 | |i|pagename|runs| 16:20:37 INFO - PID 9709 | |0;dojo.html;3234.514822776583;3258.8859389921345;3271.738260939125;3293.7859039162454;3309.7186739127173;6262.718177682003;6313.273973493044;6347.682615869211;6370;6382.266039405464;6254.7498100075945;6354.682265886699;6355.872882542347;6368.267649220341;6416;5827.47552720254;5837.445442682938;5873.882522349571;5936.43603857635;5968.970155149218;5929.6145750526075;5941.316748573904;5944.435278648543;5966.075258334965;6027.487663548603;1112.6661529786786;5803.129530570406;5847.736851841677;5920.348761636216;5953.017752070903;5436.320459942507;5775;5869.50109240715;5895.734691938873;5900.881982360372;5762.769489220426;5810.883782324372;5871.530277577784;5872.500837428824;5873.442023007808;6301.936980630181;6334.714937827809;6339.429451349401;6370.8088757337355;6422.614643121381;6726.327367263235;6763.120794296759;6772.08576842125;6822.965885170543;6858.417034552069;6838.14523184602;6894.793156205322;6896.862062758767;6918.723251069951;6951.89572156418;6401.551891367606;6405.839854003687;6415.358464153594;6438.291787903327;6461.773837915651;7024.05175301338;7046.541974771623;7131.001659767689;7145.999560061596;7153.89269160963;6660.733570657217;6689.76585819494;6757.986302054731;6771.085903402977;6818.147731533559;6627.635480048547;6730.461563074942;6824.863502730017;6897.655117244218;6901.827454313682;6759.391654751096;6774.390304872585;6775.220849602359;6776.898346524806;6779.0509328694025;6716.429103526255;6718.22740384862;6728.529002969843;6807.21717002551;6813;4896.094222568836;4932.334134891816;4979.775910084055;5003.324551185616;5018.3476148101245;4363.149185908781;4363.258246098171;4368.803403846835;4450.109978004412;4514.751688657156;6027.276726792726;6047.667378294236;6054.182685337423;6068.75724971005;6073.848153796191;6815.2843951385385;6880.827979300558;6984.61584612841;7001.964990175118;7006.159260888726;5956.0768080946655;5998.25021872266;6068.514518838482;6073.848153796191;6074.058520929263;5840.503557197686;5863.120531920161;5886.852828679232;5895.410458954156;5917;6623.4701223901975;6637.336266373324;6690.029945657852;6712.93287067123;6731.024001519752;6685.39831415175;6805.727770889307;6820.454363650896;6824.897626535606;6922.896156557555;6588.242352129567;6608.471322294301;6610;6611.933880661132;6615.239247486601;6635.104260924906;6716.731330746716;6727.865442691216;6730.394264516217;6756.121704178426;6771.796846094428;6878.724851006104;6978.965105174441;6983.336583024605;6992.475564332593;6635.767748128792;6638.302978187221;6729.596224226562;6735.528513004043;6738.831529211809;6406.711697973509;6544.934550654623;6585.242697089704;6685.598864068172;6686.264510903699;6575;6592.110065141144;6599.241087275025;6648.20221573395;6662.800115996528;6628.171478565179;6701.832454188684;6767.526273160832;6799.150106236721;6822.488313376417;6262.091996660368;6376.426121649074;6461.741530338922;6466.676666166767;6561.3438656134 16:20:37 INFO - PID 9709 | |1;ext.html;824.4682179993904;841.7222316635509;866.1122349591668;885.0972008551274;1142.3774043146484;30767.692323076764;31111.68888311116;40860.38709419354;41446;41574.37638435418;41372.37941430881;41895.790521047355;41907.79046104773;41922.58077419225;41972.37041444381;7736.767896963072;7824.334931530812;7931.9603401983195;7938.087119981189;7967.402444816633;7789.922100779005;7891;7905.209479052078;7919.881201781978;7921.841563168762;7917.247861453182;7966.044074711042;7966.163552826956;7968.641411136484;7974.401919856005;7879.290863822255;7906.367490600738;7910.327622152124;7911.5253084815095;7915.327197188246;7809.570473623949;7813.531188128731;7857.567833769141;7857.646405911748;7864.056313242448;37614.81192594047;37949;38032;38068.61931380678;38073.23853522914;3510.684038436553;3542.1852973816167;3544.2025544252597;3546.5744110706883;3551.1122219445137;31233.53149702756;31239.062828114966;31264.843675781703;31321.37357252842;31364.37271254585;5205.115130427798;5210.478952104797;5214.165733482662;5263.815766448194;5295.205719142121;188123;188259;188394;188418.99999999863;188676;47320.290195647096;47420.288695669595;47446.28830567544;47506.28740568894;47636.5236347639;49418.25872611914;49468.50531494711;49470.50529494659;49478.75260623674;49486.75256623694;12864.421101050475;14153.716925661429;14235.43058277678;14256.643583910485;14262;10398.272120951611;11355.886441135483;11480.081593472501;11480.712982175512;11502.94248528752;14077;14087.225202613954;14115.364808583638;14121.505747298796;14211.43154273838;5919.526437884959;6000.849978750479;6003.459688627957;6062.727177277033;6097.75608975636;1001.0790073132736;1021.1320377678913;1036.461040259076;1037.865077539915;1037.9429131397694;22377.664335034988;22909.656355154686;22963.88518057399;23102.306930792103;23124.190653327052;1112.1269803204445;1115.7880002799443;1122.9213955023233;1126.0316128129803;1134.6369161868122;12181.269123852597;12186;12186.81719774204;12211.938940305241;12384.690382740504;223030;223180;223371;223429;224127;50938.74530627323;51073.489265107615;51085.744571276904;51180.23229651558;51252;1427.7858321251895;1447.1389523233659;1454.083927125904;1454.2873991744182;1471.4187895781106;22008.44978875477;22469.325920222418;22567.661485078064;22591.661125083465;22718.886405567868;36901.261974759815;36987.63012369949;37002.814985924895;37041.62958370382;37085.25829483449;188581;188760;189524;189559;190938;32788;32848.34303313968;33707.325853483286;35389.82305088458;35468.82265588656;45350.54649453463;45421.54578454173;45466.77266613646;45524;45655;33130.668693313724;33149.66850331466;33154.17114572058;33646.327073457905;34002.65997339995;32930.17674558155;33155.668443315255;33245;33449.331013380084;33808.49287260644;36598.268034638626;36831.44752828763;36998.6300137006;37155;37200.81399592985;32591;32743.508847366833;32815.67184328126;32892.83553582217;33365;22536.323910282717;23211.3036608902;23319.533609328057;23358.532829343658;24814.37964050841 16:20:37 INFO - PID 9709 | |2;jquery.html;567.4876454249426;570.7289037707087;574.7959474386595;580.4862696513584;591.9348871624121;22310.55378892421;22886.084556617756;22906.88546557265;22946.426339341524;22986;24194.63708044381;25104.246872593805;26260.212193634223;26478.73521264787;26595.202143935665;6721;6837.042814006043;6839.1451068616425;6841.828954276133;6868.828279293008;6747.696353664097;6815.931840681579;6823.692933817965;6970.721171153148;7227.168875579323;7195.172555156171;7229.602371869545;7240.601766902818;7246.202917679051;7263.309985551364;7222.74720384784;7243.384312333458;7271.963640181818;7278.781636550885;7286.672099755524;7052.682629281695;7070.151581810164;7081.645917704094;7082;7082.645867706646;30940.535891961637;31254.37491250185;31869.362612747846;32107.357852843044;32113.83943080293;2033.8372930165551;2061.7628972668185;2066.555690526529;2069.606774712815;2072.8445366597566;21411.892940535352;21729.2394766184;21746.238881639067;21750.782492175033;21757.891210544003;2146.4526545730737;2166.5125346797004;2227.031241409976;2227.309534044435;2244.730632324115;134045;138912.30543847216;139051.30474347563;139131;139182.3040884789;20237.696434553494;21402.357929262147;21461.78538214598;21643.35069947904;22148.446288842908;43236.3514547282;43328.3500747489;43365.34951975723;43391.349129763075;43458.565414346085;10378.896211037792;10561;10718.142548596094;10825.079868211133;10902.618408355669;8700.869486957701;8718.476891386537;8791.208791208823;8804.163604457566;8898.288136949028;8651.870221946676;10558.683239502827;10916.727081823017;11133.888661113448;11526.94236528812;3973.4834471518516;4128.690348223835;4212.241796476603;4298.591633794785;4418.955810441855;953.1278879824882;977.8337682593834;978.8825340958988;997.3168379659957;1003.8042581696499;19169.137388817537;19807.30674426417;19842.404727858186;20061.398158055283;20289.695654565192;1052.7947050325267;1054.3831858362944;1055.7941201465794;1058.2063452410691;1059.279689810936;10779.461026948778;10850.782984340269;10996.945015274872;11022.50398732059;11347.602833900773;422745;434065;436557;437625;447968;106891.4655426718;107023;107165.4641726802;109659;111621.44189279164;1169.1173164260929;1180.3331117918349;1200.711829160995;1206.4450352837619;1216.2094638484878;17422.30310787605;17693.823061769217;17720.113994300493;17730.73403898917;18159.546011349823;53697.463025369245;54665;55102.72448637731;55781.442185579246;57075;67984;68620.31379686139;68676.31323686699;71022.64488677523;74908;14459.638509037359;14761.630959226106;14966.551003469913;15135.772963405338;15235.3905843765;23575.174868879505;23680.407989799704;23725.525489490457;23819.404514886573;23847.165349212693;18541.629167416846;18879.05604719731;18932.905335473784;19001.524961876064;19107;15119;16633.83366166323;16696.83303166953;17182.828171718123;17613.38353157633;18203;19556.90221548883;19638.509037274183;19665.8033419664;19767.308144214883;14350.928245359124;14717.632059198606;14840.925795370955;14864.033837800509;14867.479638212611;18802.153903074668;18976.146073427004;18995.810041899957;19065.332713354965;19151.616967660848 16:20:37 INFO - PID 9709 | |3;mootools.html;1370.8354997400313;1411.8305803303606;1426.0588011912141;1432.9856701432982;1435.4330039634344;14892;15166.165860877674;15542.067475951451;15572.844271557295;15582.298796554154;15744.29150688219;15956.920215398906;15973.281202345894;15977.920110399431;15981.440649577267;2733.166384252807;2783.512885245074;2786.192004318755;2786.6934637189893;2788.2750484873877;2668.452967141741;2782.5687018512162;2787.6515435570454;2794.5808128780745;2799.020342879986;2735.575985722216;2745.8764355604044;2763.433496133298;2767.349672826884;2769.5014897318474;2700.122460200423;2724.3189202699227;2727.1409506005643;2733.986330068347;2738.6713594368603;2682.3964607963053;2696.9595456068173;2698.244491542371;2700.8784604692837;2704.688960769517;14851.628709282246;15170.469033583882;15192.164430956293;15202.239888005666;15224.46714365003;8016.599170041474;8302.750917472486;8314.085450600429;8314.750557483285;8316.459430137022;5262.736863156827;5423.566114710814;5473.370562385297;5586.972065139689;5605.075162598167;1852.7498787663762;1867.206437264171;1869.2709843161178;1895.2419032387018;1906.256559941624;26477.867610662015;26559.468810623872;26720.86639566809;26721;26735.331616709736;6421.8715625688155;6539.771108011197;6770.593764374154;6793.320667933167;6803.931960680429;16978;17123;17142.40001599963;17151.914240428967;17252.396166134124;7287.089113860768;7732.265434783687;7983.441159118806;7993.040835099825;8006.079300880473;5961.791337303173;6211.751529938751;7325.4139668826365;7328.59692716895;7330.413566914634;4969.3539839821315;5427.728613569306;5480.698561579152;5487.533559647395;5497.065498865202;1613.5401410597924;1613.5562720252024;1614.9757753633705;1625.3498600559872;1640.8523232909095;926.2497377124416;934.4860326820221;943.3679434778714;944.4946953380046;946.9479178645103;7901.209879012168;7932.246436588515;7933.880991785128;7991.480553763987;8004.679812807539;726.2737262737263;735.3492159438935;740.1451323721111;741.5217184915699;749.2320371619134;4921.138800709933;5445.809396671098;5536.141898005735;5630.870678234577;5656.462636049651;21180.894095529733;21624.89187554052;21813.78186218149;21964.231251906425;22025.449363766034;20856.89571552163;21473.785262147492;21518;21534.676979845313;21700.131994720352;11129.220954533104;11203.719907002227;11251.606193783178;11274;11276.928691774268;8636.352273579632;8701.912980870111;8757.781055473664;8835.116488351114;8914.509701966454;17635.382761603283;18160.545986350447;18266.543336416697;18270.725939110656;18298.817011829713;18895.43313700591;18951.810481895554;19158;19172.328968486036;19194.232230710615;11187.272827266528;11339.979401853872;11341.206115571831;11341.4896329667;11348.489317980875;13654.317284135555;13912.443502259795;14007.859921401063;14042.578722638336;14051.718965620426;11274.436278186222;11343.943280283545;11510.94244528772;11606.593769218036;11784.292942423483;10867.565297388015;10941.671749847199;10991.890081099087;11184.328940263611;11328.660140195807;10168.440735759605;10328.586856525655;10332.431716255678;10342.431166285927;10351.689449316533;7621.542707437571;7639.885401718868;7670;7694.807629809299;7714.4599878008;7420.220876807857;7563;7575.886361704689;7610;7633.656485458278 16:20:37 INFO - PID 9709 | |4;prototype.html;499.51047972986447;520.9947900520995;524.2634099090776;526.8498477933789;529.4546616984505;6276.968615156929;6303.495720342373;6314.842128946779;6325.7153428095735;6385.744570217197;6132.754689812414;6212.689365531716;6245.812625621227;6250.156228909096;6251.2498500180045;5189.792408303663;5214.921776173361;5229.79080836767;5258.422473258026;5280.683159010472;4937.160682683934;4988.501149885001;5176.922346164811;5180.870478238036;5205.713685747282;4998.200287953928;5011.974940125312;5019.498050194969;5044.747762611873;5049.3183420238165;4919.3604831371695;4984.651074424792;4991.725455099968;5001.674891132065;5071.264666623338;4507.932381014288;4516.954830451687;4539.931901021488;4551.135284296006;4561.384213131251;6084.361142080063;6088.812681527104;6134.171886795315;6162.13730077785;6176.536759743038;5691.865550418593;5730.828075157752;5731.914021289684;5768.394318596529;5781.392953739839;6579.638119903403;6596.571222870546;6626.536142470029;6642.368974947372;6642.767503137416;4836.854894353175;4859.198232291703;4871.488493708145;4891.241857512091;4930.605551555867;6733.8653226935185;6746.224184218829;6790.932090679079;6876.656167191621;7100.609466479342;5354.223637572608;5501.862453438696;5522.723863806874;5523.8895222096135;5573.804916827891;5568.1091025435735;5585.799053203581;5591.720413979285;5609.467100625434;5626;5929.466348028698;5999.700014999233;6051.183090282843;6054.212952316171;6110.633361998294;6777.152855893013;6800.489963252776;6813.216480104851;6819.352161544645;6830.385265326145;1940.796216397258;1943.3392646500258;1948.4349538633921;1951.5511432370633;1952.4142757172792;1373.924434156111;1439.5177615498926;1441.5170917742673;1469.5517867050653;1474.8201438848878;5845.678487683218;5921.230240068763;5966.850828729316;6002.069679199731;6006.759729610854;6652.767153149616;6740.595564266159;6756.695948682321;6808.183018037867;6817.897731534031;5804.303483581997;5874.588778785529;5879.000569903127;5895.263092113486;5943.167956486095;3558.1460449491938;3599.9820000899826;3632;3649.087728067983;3660.9749270204384;6635.9336406636285;6637.435817955431;6638.269790323061;6693.623763563962;6721.395074443226;6898.344657257552;6926.653667316614;6965.233824279325;7064.1876184238445;7070.010198572204;6533.248676402274;6557.786809440268;6583.967080164664;6592.8351791205605;6649.5677780943115;6746.537513807523;6798.640271945729;6799.93200067993;6825.692843822139;6871.567838743882;6788.321167883172;6799.2520822708475;6824.351686589965;6854.794356169323;6917.412019978358;6628.90056649141;6655.201375834931;6672.864683301297;6675.23234828001;6692.698828552824;5429.321334833146;5448.727563621882;5460.481254280837;5511.035568775528;5527.866787308672;6534.607923524603;6582;6608.273089960005;6651.800445986628;6722.731090756315;6497.159616086046;6511.153550038464;6523.738130934533;6526.771562995273;6588.901166482411;6451.3226111257645;6495.9025614614875;6542.509311801729;6567.211934567882;6619.966900165468;6340.175777148941;6400.423961843457;6424.871502570016;6443.581167224115;6474.935250647622;6568.704408301734;6581.243157037002;6611.140551728245;6653.234877989094;6674.065630811593;6419.967900160469;6447.516436267205;6456;6488.967555162383;6492.902606460812 16:20:37 INFO - PID 9709 | |5;yui.html;910.3809409601472;920.5351297594716;923.233716015707;933.7618907178669;949.9905000949989;18681;19601.607967840653;19651;19675.901620491877;21602.243921462752;21201.57596848062;21610.459738506546;21844.672329915025;21923.232686855965;21924.45188870279;976.2385339435234;997.2819569909653;1000.8598796168542;1001.8747656542932;1001.8747656542932;907.8728977943093;935.5696379665362;999.5951639585979;1009.2884516415946;1012.1953047327355;1039.740064983754;1046.947652617366;1051.8264486359742;1052.2686732720724;1064.7604289034982;1042.8227201375746;1043.4417586591137;1043.457402150885;1043.478260869569;1052.847337136115;760.3499008347839;760.8364201696662;762.5310434082999;765.3226894198618;771.6334740998037;19275.325363612206;19811.702824457643;19878.90060549688;19906.60186796256;19915;21094.3671689848;21433.142674293165;21479.463013424633;21735.23926662575;21840.563188736138;16972;17243.568910777205;17263.30946762128;17387.478375648752;17568.29726810926;3514.6309637488207;3538.6284440133672;3540.7344449166158;3542.574891013069;3543.787372757643;123642.38178809048;126438;128234;130394.34802826114;131073;16498;16576.502704918872;16776.496705098867;16947.661046778994;16994.150292485327;29391.26521836971;29457.705422945495;29537.409251814843;29619.703802961696;30101.247468813453;7852.450328477066;8196.221358970888;8435.662573497115;8722.215000649972;9170.587323570455;4499.302608095751;4615.5845973862515;4616.330632058332;4621;4633.374494443207;4914.680545764514;4969.801207951641;4978.77595508203;4995.775190116454;5005.1491246488185;1026.650938680852;1053.947302634865;1057.5346847387125;1061.2889364126054;1069.4919913041242;675.601395176848;683.3337495941418;684.1789852177407;694.3230350408312;703.0508813102373;10433.32183408076;10433.739156521147;10620.946895265473;10656.733581660366;10906.400147991937;678.0845858091537;683.1699485125596;684.1140722764032;689.3830022130265;700.264722041854;5462.890742185134;5482.424345443751;6093.086037094471;6330.715117819709;6341.651209183539;104032;106550;106781;106940;112147.43926280316;90219.54890225508;91998.54000730088;93341;97772;97842.5107874456;84577;86700;87456.81357965975;88126;91246.54376728075;12700.174488658207;12761.553345632858;12761.680957975754;12876.806847897102;12878;39156.412653809646;39959.80020099881;40187.3971890416;40366.1926761469;40591.79704101461;67277.66361168359;67731.66134169298;69511.65244173747;71657.6417117911;72947.27052729405;14088.718225635635;17985.19066641978;18110.366137185134;18159.36442224516;18325.725114123565;18891.811081889005;19091.809081909003;20521.589568208852;21476.140954362276;21522.461938451663;18712.906435467736;18904.14931328121;19038.904805475882;19102.235910563977;19182.616347673247;18450.723239151153;18667.15997780076;18783.53041173927;18801.529961751065;19529.8047019528;18274.90862545679;18331;18350.35773747966;18449.169787359868;18733.71899421537;12805.935970320088;13134.343282835629;13281.800772988607;13827.170369777443;13991.370388332345;14000.859991400363;14195.716085678023;14753.778693319824;15012.924935375253;15013.6997260052 16:20:37 INFO - PID 9709 | __end_tp_report 16:20:37 INFO - PID 9709 | __start_cc_report 16:20:37 INFO - PID 9709 | _x_x_mozilla_cycle_collect,391 16:20:37 INFO - PID 9709 | __end_cc_report 16:20:37 INFO - PID 9709 | __startTimestamp1504653637190__endTimestamp 16:20:37 INFO - PID 9709 | 16:20:37 INFO - PID 9709 | ------- Summary: start ------- 16:20:37 INFO - PID 9709 | Number of tests: 6 16:20:37 INFO - PID 9709 | 16:20:37 INFO - PID 9709 | [#0] dojo.html Cycles:165 Average:6244.33 Median:6575.00 stddev:877.85 (13.4%) stddev-sans-first:848.19 16:20:37 INFO - PID 9709 | Values: 3234.5 3258.9 3271.7 3293.8 3309.7 6262.7 6313.3 6347.7 6370.0 6382.3 6254.7 6354.7 6355.9 6368.3 6416.0 5827.5 5837.4 5873.9 5936.4 5969.0 5929.6 5941.3 5944.4 5966.1 6027.5 1112.7 5803.1 5847.7 5920.3 5953.0 5436.3 5775.0 5869.5 5895.7 5900.9 5762.8 5810.9 5871.5 5872.5 5873.4 6301.9 6334.7 6339.4 6370.8 6422.6 6726.3 6763.1 6772.1 6823.0 6858.4 6838.1 6894.8 6896.9 6918.7 6951.9 6401.6 6405.8 6415.4 6438.3 6461.8 7024.1 7046.5 7131.0 7146.0 7153.9 6660.7 6689.8 6758.0 6771.1 6818.1 6627.6 6730.5 6824.9 6897.7 6901.8 6759.4 6774.4 6775.2 6776.9 6779.1 6716.4 6718.2 6728.5 6807.2 6813.0 4896.1 4932.3 4979.8 5003.3 5018.3 4363.1 4363.3 4368.8 4450.1 4514.8 6027.3 6047.7 6054.2 6068.8 6073.8 6815.3 6880.8 6984.6 7002.0 7006.2 5956.1 5998.3 6068.5 6073.8 6074.1 5840.5 5863.1 5886.9 5895.4 5917.0 6623.5 6637.3 6690.0 6712.9 6731.0 6685.4 6805.7 6820.5 6824.9 6922.9 6588.2 6608.5 6610.0 6611.9 6615.2 6635.1 6716.7 6727.9 6730.4 6756.1 6771.8 6878.7 6979.0 6983.3 6992.5 6635.8 6638.3 6729.6 6735.5 6738.8 6406.7 6544.9 6585.2 6685.6 6686.3 6575.0 6592.1 6599.2 6648.2 6662.8 6628.2 6701.8 6767.5 6799.2 6822.5 6262.1 6376.4 6461.7 6466.7 6561.3 16:20:37 INFO - PID 9709 | 16:20:37 INFO - PID 9709 | [#1] ext.html Cycles:180 Average:36937.48 Median:18756.67 stddev:52049.67 (277.5%) stddev-sans-first:52125.05 16:20:37 INFO - PID 9709 | Values: 824.5 841.7 866.1 885.1 1142.4 30767.7 31111.7 40860.4 41446.0 41574.4 41372.4 41895.8 41907.8 41922.6 41972.4 7736.8 7824.3 7932.0 7938.1 7967.4 7789.9 7891.0 7905.2 7919.9 7921.8 7917.2 7966.0 7966.2 7968.6 7974.4 7879.3 7906.4 7910.3 7911.5 7915.3 7809.6 7813.5 7857.6 7857.6 7864.1 37614.8 37949.0 38032.0 38068.6 38073.2 3510.7 3542.2 3544.2 3546.6 3551.1 31233.5 31239.1 31264.8 31321.4 31364.4 5205.1 5210.5 5214.2 5263.8 5295.2 188123.0 188259.0 188394.0 188419.0 188676.0 47320.3 47420.3 47446.3 47506.3 47636.5 49418.3 49468.5 49470.5 49478.8 49486.8 12864.4 14153.7 14235.4 14256.6 14262.0 10398.3 11355.9 11480.1 11480.7 11502.9 14077.0 14087.2 14115.4 14121.5 14211.4 5919.5 6000.8 6003.5 6062.7 6097.8 1001.1 1021.1 1036.5 1037.9 1037.9 22377.7 22909.7 22963.9 23102.3 23124.2 1112.1 1115.8 1122.9 1126.0 1134.6 12181.3 12186.0 12186.8 12211.9 12384.7 223030.0 223180.0 223371.0 223429.0 224127.0 50938.7 51073.5 51085.7 51180.2 51252.0 1427.8 1447.1 1454.1 1454.3 1471.4 22008.4 22469.3 22567.7 22591.7 22718.9 36901.3 36987.6 37002.8 37041.6 37085.3 188581.0 188760.0 189524.0 189559.0 190938.0 32788.0 32848.3 33707.3 35389.8 35468.8 45350.5 45421.5 45466.8 45524.0 45655.0 33130.7 33149.7 33154.2 33646.3 34002.7 32930.2 33155.7 33245.0 33449.3 33808.5 36598.3 36831.4 36998.6 37155.0 37200.8 32591.0 32743.5 32815.7 32892.8 33365.0 22536.3 23211.3 23319.5 23358.5 24814.4 16:20:37 INFO - PID 9709 | 16:20:37 INFO - PID 9709 | [#2] jquery.html Cycles:180 Average:34108.32 Median:21953.17 stddev:73937.39 (336.8%) stddev-sans-first:74101.92 16:20:37 INFO - PID 9709 | Values: 567.5 570.7 574.8 580.5 591.9 22310.6 22886.1 22906.9 22946.4 22986.0 24194.6 25104.2 26260.2 26478.7 26595.2 6721.0 6837.0 6839.1 6841.8 6868.8 6747.7 6815.9 6823.7 6970.7 7227.2 7195.2 7229.6 7240.6 7246.2 7263.3 7222.7 7243.4 7272.0 7278.8 7286.7 7052.7 7070.2 7081.6 7082.0 7082.6 30940.5 31254.4 31869.4 32107.4 32113.8 2033.8 2061.8 2066.6 2069.6 2072.8 21411.9 21729.2 21746.2 21750.8 21757.9 2146.5 2166.5 2227.0 2227.3 2244.7 134045.0 138912.3 139051.3 139131.0 139182.3 20237.7 21402.4 21461.8 21643.4 22148.4 43236.4 43328.4 43365.3 43391.3 43458.6 10378.9 10561.0 10718.1 10825.1 10902.6 8700.9 8718.5 8791.2 8804.2 8898.3 8651.9 10558.7 10916.7 11133.9 11526.9 3973.5 4128.7 4212.2 4298.6 4419.0 953.1 977.8 978.9 997.3 1003.8 19169.1 19807.3 19842.4 20061.4 20289.7 1052.8 1054.4 1055.8 1058.2 1059.3 10779.5 10850.8 10996.9 11022.5 11347.6 422745.0 434065.0 436557.0 437625.0 447968.0 106891.5 107023.0 107165.5 109659.0 111621.4 1169.1 1180.3 1200.7 1206.4 1216.2 17422.3 17693.8 17720.1 17730.7 18159.5 53697.5 54665.0 55102.7 55781.4 57075.0 67984.0 68620.3 68676.3 71022.6 74908.0 14459.6 14761.6 14966.6 15135.8 15235.4 23575.2 23680.4 23725.5 23819.4 23847.2 18541.6 18879.1 18932.9 19001.5 19107.0 15119.0 16633.8 16696.8 17182.8 17613.4 18203.0 19556.9 19638.5 19665.8 19767.3 14350.9 14717.6 14840.9 14864.0 14867.5 18802.2 18976.1 18995.8 19065.3 19151.6 16:20:37 INFO - PID 9709 | 16:20:37 INFO - PID 9709 | [#3] mootools.html Cycles:180 Average:9387.69 Median:14708.86 stddev:6672.03 (45.4%) stddev-sans-first:6663.56 16:20:37 INFO - PID 9709 | Values: 1370.8 1411.8 1426.1 1433.0 1435.4 14892.0 15166.2 15542.1 15572.8 15582.3 15744.3 15956.9 15973.3 15977.9 15981.4 2733.2 2783.5 2786.2 2786.7 2788.3 2668.5 2782.6 2787.7 2794.6 2799.0 2735.6 2745.9 2763.4 2767.3 2769.5 2700.1 2724.3 2727.1 2734.0 2738.7 2682.4 2697.0 2698.2 2700.9 2704.7 14851.6 15170.5 15192.2 15202.2 15224.5 8016.6 8302.8 8314.1 8314.8 8316.5 5262.7 5423.6 5473.4 5587.0 5605.1 1852.7 1867.2 1869.3 1895.2 1906.3 26477.9 26559.5 26720.9 26721.0 26735.3 6421.9 6539.8 6770.6 6793.3 6803.9 16978.0 17123.0 17142.4 17151.9 17252.4 7287.1 7732.3 7983.4 7993.0 8006.1 5961.8 6211.8 7325.4 7328.6 7330.4 4969.4 5427.7 5480.7 5487.5 5497.1 1613.5 1613.6 1615.0 1625.3 1640.9 926.2 934.5 943.4 944.5 946.9 7901.2 7932.2 7933.9 7991.5 8004.7 726.3 735.3 740.1 741.5 749.2 4921.1 5445.8 5536.1 5630.9 5656.5 21180.9 21624.9 21813.8 21964.2 22025.4 20856.9 21473.8 21518.0 21534.7 21700.1 11129.2 11203.7 11251.6 11274.0 11276.9 8636.4 8701.9 8757.8 8835.1 8914.5 17635.4 18160.5 18266.5 18270.7 18298.8 18895.4 18951.8 19158.0 19172.3 19194.2 11187.3 11340.0 11341.2 11341.5 11348.5 13654.3 13912.4 14007.9 14042.6 14051.7 11274.4 11343.9 11510.9 11606.6 11784.3 10867.6 10941.7 10991.9 11184.3 11328.7 10168.4 10328.6 10332.4 10342.4 10351.7 7621.5 7639.9 7670.0 7694.8 7714.5 7420.2 7563.0 7575.9 7610.0 7633.7 16:20:37 INFO - PID 9709 | 16:20:37 INFO - PID 9709 | [#4] prototype.html Cycles:180 Average:5635.57 Median:6194.61 stddev:1527.48 (24.7%) stddev-sans-first:1482.33 16:20:37 INFO - PID 9709 | Values: 499.5 521.0 524.3 526.8 529.5 6277.0 6303.5 6314.8 6325.7 6385.7 6132.8 6212.7 6245.8 6250.2 6251.2 5189.8 5214.9 5229.8 5258.4 5280.7 4937.2 4988.5 5176.9 5180.9 5205.7 4998.2 5012.0 5019.5 5044.7 5049.3 4919.4 4984.7 4991.7 5001.7 5071.3 4507.9 4517.0 4539.9 4551.1 4561.4 6084.4 6088.8 6134.2 6162.1 6176.5 5691.9 5730.8 5731.9 5768.4 5781.4 6579.6 6596.6 6626.5 6642.4 6642.8 4836.9 4859.2 4871.5 4891.2 4930.6 6733.9 6746.2 6790.9 6876.7 7100.6 5354.2 5501.9 5522.7 5523.9 5573.8 5568.1 5585.8 5591.7 5609.5 5626.0 5929.5 5999.7 6051.2 6054.2 6110.6 6777.2 6800.5 6813.2 6819.4 6830.4 1940.8 1943.3 1948.4 1951.6 1952.4 1373.9 1439.5 1441.5 1469.6 1474.8 5845.7 5921.2 5966.9 6002.1 6006.8 6652.8 6740.6 6756.7 6808.2 6817.9 5804.3 5874.6 5879.0 5895.3 5943.2 3558.1 3600.0 3632.0 3649.1 3661.0 6635.9 6637.4 6638.3 6693.6 6721.4 6898.3 6926.7 6965.2 7064.2 7070.0 6533.2 6557.8 6584.0 6592.8 6649.6 6746.5 6798.6 6799.9 6825.7 6871.6 6788.3 6799.3 6824.4 6854.8 6917.4 6628.9 6655.2 6672.9 6675.2 6692.7 5429.3 5448.7 5460.5 5511.0 5527.9 6534.6 6582.0 6608.3 6651.8 6722.7 6497.2 6511.2 6523.7 6526.8 6588.9 6451.3 6495.9 6542.5 6567.2 6620.0 6340.2 6400.4 6424.9 6443.6 6474.9 6568.7 6581.2 6611.1 6653.2 6674.1 6420.0 6447.5 6456.0 6489.0 6492.9 16:20:37 INFO - PID 9709 | 16:20:37 INFO - PID 9709 | [#5] yui.html Cycles:180 Average:23735.55 Median:20218.29 stddev:31915.06 (157.9%) stddev-sans-first:31958.57 16:20:37 INFO - PID 9709 | Values: 910.4 920.5 923.2 933.8 950.0 18681.0 19601.6 19651.0 19675.9 21602.2 21201.6 21610.5 21844.7 21923.2 21924.5 976.2 997.3 1000.9 1001.9 1001.9 907.9 935.6 999.6 1009.3 1012.2 1039.7 1046.9 1051.8 1052.3 1064.8 1042.8 1043.4 1043.5 1043.5 1052.8 760.3 760.8 762.5 765.3 771.6 19275.3 19811.7 19878.9 19906.6 19915.0 21094.4 21433.1 21479.5 21735.2 21840.6 16972.0 17243.6 17263.3 17387.5 17568.3 3514.6 3538.6 3540.7 3542.6 3543.8 123642.4 126438.0 128234.0 130394.3 131073.0 16498.0 16576.5 16776.5 16947.7 16994.2 29391.3 29457.7 29537.4 29619.7 30101.2 7852.5 8196.2 8435.7 8722.2 9170.6 4499.3 4615.6 4616.3 4621.0 4633.4 4914.7 4969.8 4978.8 4995.8 5005.1 1026.7 1053.9 1057.5 1061.3 1069.5 675.6 683.3 684.2 694.3 703.1 10433.3 10433.7 10620.9 10656.7 10906.4 678.1 683.2 684.1 689.4 700.3 5462.9 5482.4 6093.1 6330.7 6341.7 104032.0 106550.0 106781.0 106940.0 112147.4 90219.5 91998.5 93341.0 97772.0 97842.5 84577.0 86700.0 87456.8 88126.0 91246.5 12700.2 12761.6 12761.7 12876.8 12878.0 39156.4 39959.8 40187.4 40366.2 40591.8 67277.7 67731.7 69511.7 71657.6 72947.3 14088.7 17985.2 18110.4 18159.4 18325.7 18891.8 19091.8 20521.6 21476.1 21522.5 18712.9 18904.1 19038.9 19102.2 19182.6 18450.7 18667.2 18783.5 18801.5 19529.8 18274.9 18331.0 18350.4 18449.2 18733.7 12805.9 13134.3 13281.8 13827.2 13991.4 14000.9 14195.7 14753.8 15012.9 15013.7 16:20:37 INFO - PID 9709 | -------- Summary: end -------- 16:20:37 INFO - PID 9709 | 16:20:37 INFO - TEST-INFO | 9709: exit 0 16:20:37 INFO - TEST-OK | dromaeo_css | took 1107466ms 16:20:37 INFO - TEST-START | kraken 16:20:37 INFO - Initialising browser for kraken test... 16:20:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34447/getInfo.html -profile /tmp/tmpeDAqbx/profile 16:20:37 INFO - TEST-INFO | started process 10095 (/builds/slave/test/build/application/firefox/firefox http://localhost:34447/getInfo.html) 16:20:43 INFO - TEST-INFO | 10095: exit 0 16:20:43 INFO - Browser initialized. 16:20:43 INFO - Running cycle 1/1 for kraken test... 16:20:43 INFO - Using env: {'DISPLAY': ':0', 16:20:43 INFO - 'HOME': '/home/cltbld', 16:20:43 INFO - 'JSGC_DISABLE_POISONING': '1', 16:20:43 INFO - 'LANG': 'en_US.UTF-8', 16:20:43 INFO - 'LANGUAGE': 'en_US:en', 16:20:43 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:20:43 INFO - 'LOGNAME': 'cltbld', 16:20:43 INFO - 'MAIL': '/var/mail/cltbld', 16:20:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:20:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:20:43 INFO - 'MOZ_CRASHREPORTER': '1', 16:20:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:43 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:20:43 INFO - 'MOZ_NO_REMOTE': '1', 16:20:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:20:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:43 INFO - 'NO_EM_RESTART': '1', 16:20:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:43 INFO - 'PWD': '/builds/slave/test', 16:20:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:20:43 INFO - 'RUST_BACKTRACE': 'full', 16:20:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:20:43 INFO - 'SHELL': '/bin/bash', 16:20:43 INFO - 'SHLVL': '1', 16:20:43 INFO - 'STYLO_FORCE_ENABLED': '1', 16:20:43 INFO - 'TERM': 'linux', 16:20:43 INFO - 'TMOUT': '86400', 16:20:43 INFO - 'USER': 'cltbld', 16:20:43 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825', 16:20:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:43 INFO - '_': '/tools/buildbot/bin/python'} 16:20:43 INFO - TEST-INFO | started process 10291 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeDAqbx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:44 INFO - PID 10291 | 16:20:44 INFO - PID 10291 | (/builds/slave/test/build/application/firefox/firefox:10343): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:20:44 INFO - PID 10291 | 16:20:54 INFO - PID 10291 | RSS: Main: 167391232 16:20:54 INFO - PID 10291 | 16:20:57 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34447/tests/kraken/driver.html?testName=audio-beat-detection) 16:20:58 INFO - PID 10291 | RSS: Main: 173076480 16:20:58 INFO - PID 10291 | 16:21:01 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34447/tests/kraken/driver.html?testName=audio-dft) 16:21:01 INFO - PID 10291 | RSS: Main: 177778688 16:21:01 INFO - PID 10291 | 16:21:04 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34447/tests/kraken/driver.html?testName=audio-fft) 16:21:05 INFO - PID 10291 | RSS: Main: 171573248 16:21:05 INFO - PID 10291 | 16:21:07 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34447/tests/kraken/driver.html?testName=audio-oscillator) 16:21:08 INFO - PID 10291 | RSS: Main: 171737088 16:21:08 INFO - PID 10291 | 16:21:10 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34447/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:21:10 INFO - PID 10291 | RSS: Main: 171995136 16:21:10 INFO - PID 10291 | 16:21:14 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34447/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:14 INFO - PID 10291 | RSS: Main: 172777472 16:21:14 INFO - PID 10291 | 16:21:18 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34447/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:18 INFO - PID 10291 | RSS: Main: 172437504 16:21:18 INFO - PID 10291 | 16:21:21 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34447/tests/kraken/driver.html?testName=json-parse-financial) 16:21:22 INFO - PID 10291 | RSS: Main: 173002752 16:21:22 INFO - PID 10291 | 16:21:23 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34447/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:24 INFO - PID 10291 | RSS: Main: 172838912 16:21:24 INFO - PID 10291 | 16:21:26 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34447/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:26 INFO - PID 10291 | RSS: Main: 173133824 16:21:26 INFO - PID 10291 | 16:21:28 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34447/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:29 INFO - PID 10291 | RSS: Main: 173232128 16:21:29 INFO - PID 10291 | 16:21:32 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:33 INFO - PID 10291 | RSS: Main: 173965312 16:21:33 INFO - PID 10291 | 16:21:36 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:36 INFO - PID 10291 | RSS: Main: 173420544 16:21:36 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | Cycle 1(1): loaded http://localhost:34447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34447/tests/kraken/driver.html?testName=ai-astar) 16:21:38 INFO - PID 10291 | RSS: Main: 175095808 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | __start_tp_report 16:21:38 INFO - PID 10291 | _x_x_mozilla_page_load 16:21:38 INFO - PID 10291 | _x_x_mozilla_page_load_details 16:21:38 INFO - PID 10291 | |i|pagename|runs| 16:21:38 INFO - PID 10291 | |0;ai-astar;96;107;100;106;106;110;107;97;106;109 16:21:38 INFO - PID 10291 | |1;audio-beat-detection;148;117;149;173;125;144;140;116;169;121 16:21:38 INFO - PID 10291 | |2;audio-dft;163;162;161;162;163;163;163;164;166;164 16:21:38 INFO - PID 10291 | |3;audio-fft;78;73;74;168;77;74;74;74;165;75 16:21:38 INFO - PID 10291 | |4;audio-oscillator;80;79;129;79;79;79;127;80;79;129 16:21:38 INFO - PID 10291 | |5;imaging-gaussian-blur;114;114;116;115;113;113;113;113;113;114 16:21:38 INFO - PID 10291 | |6;imaging-darkroom;150;148;148;147;148;148;147;149;150;149 16:21:38 INFO - PID 10291 | |7;imaging-desaturate;94;94;94;94;94;94;95;94;95;94 16:21:38 INFO - PID 10291 | |8;json-parse-financial;53;53;53;52;53;53;52;51;51;63 16:21:38 INFO - PID 10291 | |9;json-stringify-tinderbox;39;38;37;38;38;39;38;38;38;38 16:21:38 INFO - PID 10291 | |10;stanford-crypto-aes;74;78;74;75;76;73;74;75;78;73 16:21:38 INFO - PID 10291 | |11;stanford-crypto-ccm;118;125;119;118;122;128;124;127;116;159 16:21:38 INFO - PID 10291 | |12;stanford-crypto-pbkdf2;173;173;175;172;181;175;166;173;190;185 16:21:38 INFO - PID 10291 | |13;stanford-crypto-sha256-iterative;64;66;74;68;63;66;63;65;64;65 16:21:38 INFO - PID 10291 | __end_tp_report 16:21:38 INFO - PID 10291 | __start_cc_report 16:21:38 INFO - PID 10291 | _x_x_mozilla_cycle_collect,973 16:21:38 INFO - PID 10291 | __end_cc_report 16:21:38 INFO - PID 10291 | __startTimestamp1504653698711__endTimestamp 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | ------- Summary: start ------- 16:21:38 INFO - PID 10291 | Number of tests: 14 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#0] ai-astar Cycles:10 Average:104.40 Median:108.00 stddev:4.93 (4.6%) stddev-sans-first:4.18 16:21:38 INFO - PID 10291 | Values: 96.0 107.0 100.0 106.0 106.0 110.0 107.0 97.0 106.0 109.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:146.00 stddev:20.50 (14.0%) stddev-sans-first:21.55 16:21:38 INFO - PID 10291 | Values: 148.0 117.0 149.0 173.0 125.0 144.0 140.0 116.0 169.0 121.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.37 (0.8%) stddev-sans-first:1.45 16:21:38 INFO - PID 10291 | Values: 163.0 162.0 161.0 162.0 163.0 163.0 163.0 164.0 166.0 164.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#3] audio-fft Cycles:10 Average:93.20 Median:74.00 stddev:38.67 (52.3%) stddev-sans-first:40.62 16:21:38 INFO - PID 10291 | Values: 78.0 73.0 74.0 168.0 77.0 74.0 74.0 74.0 165.0 75.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:79.00 stddev:23.70 (30.0%) stddev-sans-first:24.59 16:21:38 INFO - PID 10291 | Values: 80.0 79.0 129.0 79.0 79.0 79.0 127.0 80.0 79.0 129.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:1.03 (0.9%) stddev-sans-first:1.09 16:21:38 INFO - PID 10291 | Values: 114.0 114.0 116.0 115.0 113.0 113.0 113.0 113.0 113.0 114.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:1.07 (0.7%) stddev-sans-first:0.97 16:21:38 INFO - PID 10291 | Values: 150.0 148.0 148.0 147.0 148.0 148.0 147.0 149.0 150.0 149.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:21:38 INFO - PID 10291 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 94.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.00 stddev:3.47 (6.5%) stddev-sans-first:3.68 16:21:38 INFO - PID 10291 | Values: 53.0 53.0 53.0 52.0 53.0 53.0 52.0 51.0 51.0 63.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.57 (1.5%) stddev-sans-first:0.50 16:21:38 INFO - PID 10291 | Values: 39.0 38.0 37.0 38.0 38.0 39.0 38.0 38.0 38.0 38.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:75.00 stddev:1.83 (2.4%) stddev-sans-first:1.90 16:21:38 INFO - PID 10291 | Values: 74.0 78.0 74.0 75.0 76.0 73.0 74.0 75.0 78.0 73.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#11] stanford-crypto-ccm Cycles:10 Average:125.60 Median:124.50 stddev:12.43 (10.0%) stddev-sans-first:12.88 16:21:38 INFO - PID 10291 | Values: 118.0 125.0 119.0 118.0 122.0 128.0 124.0 127.0 116.0 159.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.30 Median:175.00 stddev:7.04 (4.0%) stddev-sans-first:7.37 16:21:38 INFO - PID 10291 | Values: 173.0 173.0 175.0 172.0 181.0 175.0 166.0 173.0 190.0 185.0 16:21:38 INFO - PID 10291 | 16:21:38 INFO - PID 10291 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:65.50 stddev:3.26 (5.0%) stddev-sans-first:3.39 16:21:38 INFO - PID 10291 | Values: 64.0 66.0 74.0 68.0 63.0 66.0 63.0 65.0 64.0 65.0 16:21:38 INFO - PID 10291 | -------- Summary: end -------- 16:21:38 INFO - PID 10291 | 16:21:39 INFO - PID 10291 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:21:39 INFO - TEST-INFO | 10291: exit 0 16:21:39 INFO - TEST-OK | kraken | took 61558ms 16:21:39 INFO - SUITE-END | took 1169s 16:21:39 INFO - Completed test suite (00:19:29) 16:21:39 INFO - javascript benchmark 16:21:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8683.377541714335, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3234.514822776583, 3258.8859389921345, 3271.738260939125, 3293.7859039162454, 3309.7186739127173, 6262.718177682003, 6313.273973493044, 6347.682615869211, 6370.0, 6382.266039405464, 6254.7498100075945, 6354.682265886699, 6355.872882542347, 6368.267649220341, 6416.0, 5827.47552720254, 5837.445442682938, 5873.882522349571, 5936.43603857635, 5968.970155149218, 5929.6145750526075, 5941.316748573904, 5944.435278648543, 5966.075258334965, 6027.487663548603, 1112.6661529786786, 5803.129530570406, 5847.736851841677, 5920.348761636216, 5953.017752070903, 5436.320459942507, 5775.0, 5869.50109240715, 5895.734691938873, 5900.881982360372, 5762.769489220426, 5810.883782324372, 5871.530277577784, 5872.500837428824, 5873.442023007808, 6301.936980630181, 6334.714937827809, 6339.429451349401, 6370.8088757337355, 6422.614643121381, 6726.327367263235, 6763.120794296759, 6772.08576842125, 6822.965885170543, 6858.417034552069, 6838.14523184602, 6894.793156205322, 6896.862062758767, 6918.723251069951, 6951.89572156418, 6401.551891367606, 6405.839854003687, 6415.358464153594, 6438.291787903327, 6461.773837915651, 7024.05175301338, 7046.541974771623, 7131.001659767689, 7145.999560061596, 7153.89269160963, 6660.733570657217, 6689.76585819494, 6757.986302054731, 6771.085903402977, 6818.147731533559, 6627.635480048547, 6730.461563074942, 6824.863502730017, 6897.655117244218, 6901.827454313682, 6759.391654751096, 6774.390304872585, 6775.220849602359, 6776.898346524806, 6779.0509328694025, 6716.429103526255, 6718.22740384862, 6728.529002969843, 6807.21717002551, 6813.0, 4896.094222568836, 4932.334134891816, 4979.775910084055, 5003.324551185616, 5018.3476148101245, 4363.149185908781, 4363.258246098171, 4368.803403846835, 4450.109978004412, 4514.751688657156, 6027.276726792726, 6047.667378294236, 6054.182685337423, 6068.75724971005, 6073.848153796191, 6815.2843951385385, 6880.827979300558, 6984.61584612841, 7001.964990175118, 7006.159260888726, 5956.0768080946655, 5998.25021872266, 6068.514518838482, 6073.848153796191, 6074.058520929263, 5840.503557197686, 5863.120531920161, 5886.852828679232, 5895.410458954156, 5917.0, 6623.4701223901975, 6637.336266373324, 6690.029945657852, 6712.93287067123, 6731.024001519752, 6685.39831415175, 6805.727770889307, 6820.454363650896, 6824.897626535606, 6922.896156557555, 6588.242352129567, 6608.471322294301, 6610.0, 6611.933880661132, 6615.239247486601, 6635.104260924906, 6716.731330746716, 6727.865442691216, 6730.394264516217, 6756.121704178426, 6771.796846094428, 6878.724851006104, 6978.965105174441, 6983.336583024605, 6992.475564332593, 6635.767748128792, 6638.302978187221, 6729.596224226562, 6735.528513004043, 6738.831529211809, 6406.711697973509, 6544.934550654623, 6585.242697089704, 6685.598864068172, 6686.264510903699, 6575.0, 6592.110065141144, 6599.241087275025, 6648.20221573395, 6662.800115996528, 6628.171478565179, 6701.832454188684, 6767.526273160832, 6799.150106236721, 6822.488313376417, 6262.091996660368, 6376.426121649074, 6461.741530338922, 6466.676666166767, 6561.3438656134], "value": 6178.985954955133, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [824.4682179993904, 841.7222316635509, 866.1122349591668, 885.0972008551274, 1142.3774043146484, 30767.692323076764, 31111.68888311116, 40860.38709419354, 41446.0, 41574.37638435418, 41372.37941430881, 41895.790521047355, 41907.79046104773, 41922.58077419225, 41972.37041444381, 7736.767896963072, 7824.334931530812, 7931.9603401983195, 7938.087119981189, 7967.402444816633, 7789.922100779005, 7891.0, 7905.209479052078, 7919.881201781978, 7921.841563168762, 7917.247861453182, 7966.044074711042, 7966.163552826956, 7968.641411136484, 7974.401919856005, 7879.290863822255, 7906.367490600738, 7910.327622152124, 7911.5253084815095, 7915.327197188246, 7809.570473623949, 7813.531188128731, 7857.567833769141, 7857.646405911748, 7864.056313242448, 37614.81192594047, 37949.0, 38032.0, 38068.61931380678, 38073.23853522914, 3510.684038436553, 3542.1852973816167, 3544.2025544252597, 3546.5744110706883, 3551.1122219445137, 31233.53149702756, 31239.062828114966, 31264.843675781703, 31321.37357252842, 31364.37271254585, 5205.115130427798, 5210.478952104797, 5214.165733482662, 5263.815766448194, 5295.205719142121, 188123.0, 188259.0, 188394.0, 188418.99999999863, 188676.0, 47320.290195647096, 47420.288695669595, 47446.28830567544, 47506.28740568894, 47636.5236347639, 49418.25872611914, 49468.50531494711, 49470.50529494659, 49478.75260623674, 49486.75256623694, 12864.421101050475, 14153.716925661429, 14235.43058277678, 14256.643583910485, 14262.0, 10398.272120951611, 11355.886441135483, 11480.081593472501, 11480.712982175512, 11502.94248528752, 14077.0, 14087.225202613954, 14115.364808583638, 14121.505747298796, 14211.43154273838, 5919.526437884959, 6000.849978750479, 6003.459688627957, 6062.727177277033, 6097.75608975636, 1001.0790073132736, 1021.1320377678913, 1036.461040259076, 1037.865077539915, 1037.9429131397694, 22377.664335034988, 22909.656355154686, 22963.88518057399, 23102.306930792103, 23124.190653327052, 1112.1269803204445, 1115.7880002799443, 1122.9213955023233, 1126.0316128129803, 1134.6369161868122, 12181.269123852597, 12186.0, 12186.81719774204, 12211.938940305241, 12384.690382740504, 223030.0, 223180.0, 223371.0, 223429.0, 224127.0, 50938.74530627323, 51073.489265107615, 51085.744571276904, 51180.23229651558, 51252.0, 1427.7858321251895, 1447.1389523233659, 1454.083927125904, 1454.2873991744182, 1471.4187895781106, 22008.44978875477, 22469.325920222418, 22567.661485078064, 22591.661125083465, 22718.886405567868, 36901.261974759815, 36987.63012369949, 37002.814985924895, 37041.62958370382, 37085.25829483449, 188581.0, 188760.0, 189524.0, 189559.0, 190938.0, 32788.0, 32848.34303313968, 33707.325853483286, 35389.82305088458, 35468.82265588656, 45350.54649453463, 45421.54578454173, 45466.77266613646, 45524.0, 45655.0, 33130.668693313724, 33149.66850331466, 33154.17114572058, 33646.327073457905, 34002.65997339995, 32930.17674558155, 33155.668443315255, 33245.0, 33449.331013380084, 33808.49287260644, 36598.268034638626, 36831.44752828763, 36998.6300137006, 37155.0, 37200.81399592985, 32591.0, 32743.508847366833, 32815.67184328126, 32892.83553582217, 33365.0, 22536.323910282717, 23211.3036608902, 23319.533609328057, 23358.532829343658, 24814.37964050841], "value": 17079.52029385369, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.4876454249426, 570.7289037707087, 574.7959474386595, 580.4862696513584, 591.9348871624121, 22310.55378892421, 22886.084556617756, 22906.88546557265, 22946.426339341524, 22986.0, 24194.63708044381, 25104.246872593805, 26260.212193634223, 26478.73521264787, 26595.202143935665, 6721.0, 6837.042814006043, 6839.1451068616425, 6841.828954276133, 6868.828279293008, 6747.696353664097, 6815.931840681579, 6823.692933817965, 6970.721171153148, 7227.168875579323, 7195.172555156171, 7229.602371869545, 7240.601766902818, 7246.202917679051, 7263.309985551364, 7222.74720384784, 7243.384312333458, 7271.963640181818, 7278.781636550885, 7286.672099755524, 7052.682629281695, 7070.151581810164, 7081.645917704094, 7082.0, 7082.645867706646, 30940.535891961637, 31254.37491250185, 31869.362612747846, 32107.357852843044, 32113.83943080293, 2033.8372930165551, 2061.7628972668185, 2066.555690526529, 2069.606774712815, 2072.8445366597566, 21411.892940535352, 21729.2394766184, 21746.238881639067, 21750.782492175033, 21757.891210544003, 2146.4526545730737, 2166.5125346797004, 2227.031241409976, 2227.309534044435, 2244.730632324115, 134045.0, 138912.30543847216, 139051.30474347563, 139131.0, 139182.3040884789, 20237.696434553494, 21402.357929262147, 21461.78538214598, 21643.35069947904, 22148.446288842908, 43236.3514547282, 43328.3500747489, 43365.34951975723, 43391.349129763075, 43458.565414346085, 10378.896211037792, 10561.0, 10718.142548596094, 10825.079868211133, 10902.618408355669, 8700.869486957701, 8718.476891386537, 8791.208791208823, 8804.163604457566, 8898.288136949028, 8651.870221946676, 10558.683239502827, 10916.727081823017, 11133.888661113448, 11526.94236528812, 3973.4834471518516, 4128.690348223835, 4212.241796476603, 4298.591633794785, 4418.955810441855, 953.1278879824882, 977.8337682593834, 978.8825340958988, 997.3168379659957, 1003.8042581696499, 19169.137388817537, 19807.30674426417, 19842.404727858186, 20061.398158055283, 20289.695654565192, 1052.7947050325267, 1054.3831858362944, 1055.7941201465794, 1058.2063452410691, 1059.279689810936, 10779.461026948778, 10850.782984340269, 10996.945015274872, 11022.50398732059, 11347.602833900773, 422745.0, 434065.0, 436557.0, 437625.0, 447968.0, 106891.4655426718, 107023.0, 107165.4641726802, 109659.0, 111621.44189279164, 1169.1173164260929, 1180.3331117918349, 1200.711829160995, 1206.4450352837619, 1216.2094638484878, 17422.30310787605, 17693.823061769217, 17720.113994300493, 17730.73403898917, 18159.546011349823, 53697.463025369245, 54665.0, 55102.72448637731, 55781.442185579246, 57075.0, 67984.0, 68620.31379686139, 68676.31323686699, 71022.64488677523, 74908.0, 14459.638509037359, 14761.630959226106, 14966.551003469913, 15135.772963405338, 15235.3905843765, 23575.174868879505, 23680.407989799704, 23725.525489490457, 23819.404514886573, 23847.165349212693, 18541.629167416846, 18879.05604719731, 18932.905335473784, 19001.524961876064, 19107.0, 15119.0, 16633.83366166323, 16696.83303166953, 17182.828171718123, 17613.38353157633, 18203.0, 19556.90221548883, 19638.509037274183, 19665.8033419664, 19767.308144214883, 14350.928245359124, 14717.632059198606, 14840.925795370955, 14864.033837800509, 14867.479638212611, 18802.153903074668, 18976.146073427004, 18995.810041899957, 19065.332713354965, 19151.616967660848], "value": 12686.899740960798, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.8354997400313, 1411.8305803303606, 1426.0588011912141, 1432.9856701432982, 1435.4330039634344, 14892.0, 15166.165860877674, 15542.067475951451, 15572.844271557295, 15582.298796554154, 15744.29150688219, 15956.920215398906, 15973.281202345894, 15977.920110399431, 15981.440649577267, 2733.166384252807, 2783.512885245074, 2786.192004318755, 2786.6934637189893, 2788.2750484873877, 2668.452967141741, 2782.5687018512162, 2787.6515435570454, 2794.5808128780745, 2799.020342879986, 2735.575985722216, 2745.8764355604044, 2763.433496133298, 2767.349672826884, 2769.5014897318474, 2700.122460200423, 2724.3189202699227, 2727.1409506005643, 2733.986330068347, 2738.6713594368603, 2682.3964607963053, 2696.9595456068173, 2698.244491542371, 2700.8784604692837, 2704.688960769517, 14851.628709282246, 15170.469033583882, 15192.164430956293, 15202.239888005666, 15224.46714365003, 8016.599170041474, 8302.750917472486, 8314.085450600429, 8314.750557483285, 8316.459430137022, 5262.736863156827, 5423.566114710814, 5473.370562385297, 5586.972065139689, 5605.075162598167, 1852.7498787663762, 1867.206437264171, 1869.2709843161178, 1895.2419032387018, 1906.256559941624, 26477.867610662015, 26559.468810623872, 26720.86639566809, 26721.0, 26735.331616709736, 6421.8715625688155, 6539.771108011197, 6770.593764374154, 6793.320667933167, 6803.931960680429, 16978.0, 17123.0, 17142.40001599963, 17151.914240428967, 17252.396166134124, 7287.089113860768, 7732.265434783687, 7983.441159118806, 7993.040835099825, 8006.079300880473, 5961.791337303173, 6211.751529938751, 7325.4139668826365, 7328.59692716895, 7330.413566914634, 4969.3539839821315, 5427.728613569306, 5480.698561579152, 5487.533559647395, 5497.065498865202, 1613.5401410597924, 1613.5562720252024, 1614.9757753633705, 1625.3498600559872, 1640.8523232909095, 926.2497377124416, 934.4860326820221, 943.3679434778714, 944.4946953380046, 946.9479178645103, 7901.209879012168, 7932.246436588515, 7933.880991785128, 7991.480553763987, 8004.679812807539, 726.2737262737263, 735.3492159438935, 740.1451323721111, 741.5217184915699, 749.2320371619134, 4921.138800709933, 5445.809396671098, 5536.141898005735, 5630.870678234577, 5656.462636049651, 21180.894095529733, 21624.89187554052, 21813.78186218149, 21964.231251906425, 22025.449363766034, 20856.89571552163, 21473.785262147492, 21518.0, 21534.676979845313, 21700.131994720352, 11129.220954533104, 11203.719907002227, 11251.606193783178, 11274.0, 11276.928691774268, 8636.352273579632, 8701.912980870111, 8757.781055473664, 8835.116488351114, 8914.509701966454, 17635.382761603283, 18160.545986350447, 18266.543336416697, 18270.725939110656, 18298.817011829713, 18895.43313700591, 18951.810481895554, 19158.0, 19172.328968486036, 19194.232230710615, 11187.272827266528, 11339.979401853872, 11341.206115571831, 11341.4896329667, 11348.489317980875, 13654.317284135555, 13912.443502259795, 14007.859921401063, 14042.578722638336, 14051.718965620426, 11274.436278186222, 11343.943280283545, 11510.94244528772, 11606.593769218036, 11784.292942423483, 10867.565297388015, 10941.671749847199, 10991.890081099087, 11184.328940263611, 11328.660140195807, 10168.440735759605, 10328.586856525655, 10332.431716255678, 10342.431166285927, 10351.689449316533, 7621.542707437571, 7639.885401718868, 7670.0, 7694.807629809299, 7714.4599878008, 7420.220876807857, 7563.0, 7575.886361704689, 7610.0, 7633.656485458278], "value": 6703.016930505404, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.51047972986447, 520.9947900520995, 524.2634099090776, 526.8498477933789, 529.4546616984505, 6276.968615156929, 6303.495720342373, 6314.842128946779, 6325.7153428095735, 6385.744570217197, 6132.754689812414, 6212.689365531716, 6245.812625621227, 6250.156228909096, 6251.2498500180045, 5189.792408303663, 5214.921776173361, 5229.79080836767, 5258.422473258026, 5280.683159010472, 4937.160682683934, 4988.501149885001, 5176.922346164811, 5180.870478238036, 5205.713685747282, 4998.200287953928, 5011.974940125312, 5019.498050194969, 5044.747762611873, 5049.3183420238165, 4919.3604831371695, 4984.651074424792, 4991.725455099968, 5001.674891132065, 5071.264666623338, 4507.932381014288, 4516.954830451687, 4539.931901021488, 4551.135284296006, 4561.384213131251, 6084.361142080063, 6088.812681527104, 6134.171886795315, 6162.13730077785, 6176.536759743038, 5691.865550418593, 5730.828075157752, 5731.914021289684, 5768.394318596529, 5781.392953739839, 6579.638119903403, 6596.571222870546, 6626.536142470029, 6642.368974947372, 6642.767503137416, 4836.854894353175, 4859.198232291703, 4871.488493708145, 4891.241857512091, 4930.605551555867, 6733.8653226935185, 6746.224184218829, 6790.932090679079, 6876.656167191621, 7100.609466479342, 5354.223637572608, 5501.862453438696, 5522.723863806874, 5523.8895222096135, 5573.804916827891, 5568.1091025435735, 5585.799053203581, 5591.720413979285, 5609.467100625434, 5626.0, 5929.466348028698, 5999.700014999233, 6051.183090282843, 6054.212952316171, 6110.633361998294, 6777.152855893013, 6800.489963252776, 6813.216480104851, 6819.352161544645, 6830.385265326145, 1940.796216397258, 1943.3392646500258, 1948.4349538633921, 1951.5511432370633, 1952.4142757172792, 1373.924434156111, 1439.5177615498926, 1441.5170917742673, 1469.5517867050653, 1474.8201438848878, 5845.678487683218, 5921.230240068763, 5966.850828729316, 6002.069679199731, 6006.759729610854, 6652.767153149616, 6740.595564266159, 6756.695948682321, 6808.183018037867, 6817.897731534031, 5804.303483581997, 5874.588778785529, 5879.000569903127, 5895.263092113486, 5943.167956486095, 3558.1460449491938, 3599.9820000899826, 3632.0, 3649.087728067983, 3660.9749270204384, 6635.9336406636285, 6637.435817955431, 6638.269790323061, 6693.623763563962, 6721.395074443226, 6898.344657257552, 6926.653667316614, 6965.233824279325, 7064.1876184238445, 7070.010198572204, 6533.248676402274, 6557.786809440268, 6583.967080164664, 6592.8351791205605, 6649.5677780943115, 6746.537513807523, 6798.640271945729, 6799.93200067993, 6825.692843822139, 6871.567838743882, 6788.321167883172, 6799.2520822708475, 6824.351686589965, 6854.794356169323, 6917.412019978358, 6628.90056649141, 6655.201375834931, 6672.864683301297, 6675.23234828001, 6692.698828552824, 5429.321334833146, 5448.727563621882, 5460.481254280837, 5511.035568775528, 5527.866787308672, 6534.607923524603, 6582.0, 6608.273089960005, 6651.800445986628, 6722.731090756315, 6497.159616086046, 6511.153550038464, 6523.738130934533, 6526.771562995273, 6588.901166482411, 6451.3226111257645, 6495.9025614614875, 6542.509311801729, 6567.211934567882, 6619.966900165468, 6340.175777148941, 6400.423961843457, 6424.871502570016, 6443.581167224115, 6474.935250647622, 6568.704408301734, 6581.243157037002, 6611.140551728245, 6653.234877989094, 6674.065630811593, 6419.967900160469, 6447.516436267205, 6456.0, 6488.967555162383, 6492.902606460812], "value": 5198.556755126569, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [910.3809409601472, 920.5351297594716, 923.233716015707, 933.7618907178669, 949.9905000949989, 18681.0, 19601.607967840653, 19651.0, 19675.901620491877, 21602.243921462752, 21201.57596848062, 21610.459738506546, 21844.672329915025, 21923.232686855965, 21924.45188870279, 976.2385339435234, 997.2819569909653, 1000.8598796168542, 1001.8747656542932, 1001.8747656542932, 907.8728977943093, 935.5696379665362, 999.5951639585979, 1009.2884516415946, 1012.1953047327355, 1039.740064983754, 1046.947652617366, 1051.8264486359742, 1052.2686732720724, 1064.7604289034982, 1042.8227201375746, 1043.4417586591137, 1043.457402150885, 1043.478260869569, 1052.847337136115, 760.3499008347839, 760.8364201696662, 762.5310434082999, 765.3226894198618, 771.6334740998037, 19275.325363612206, 19811.702824457643, 19878.90060549688, 19906.60186796256, 19915.0, 21094.3671689848, 21433.142674293165, 21479.463013424633, 21735.23926662575, 21840.563188736138, 16972.0, 17243.568910777205, 17263.30946762128, 17387.478375648752, 17568.29726810926, 3514.6309637488207, 3538.6284440133672, 3540.7344449166158, 3542.574891013069, 3543.787372757643, 123642.38178809048, 126438.0, 128234.0, 130394.34802826114, 131073.0, 16498.0, 16576.502704918872, 16776.496705098867, 16947.661046778994, 16994.150292485327, 29391.26521836971, 29457.705422945495, 29537.409251814843, 29619.703802961696, 30101.247468813453, 7852.450328477066, 8196.221358970888, 8435.662573497115, 8722.215000649972, 9170.587323570455, 4499.302608095751, 4615.5845973862515, 4616.330632058332, 4621.0, 4633.374494443207, 4914.680545764514, 4969.801207951641, 4978.77595508203, 4995.775190116454, 5005.1491246488185, 1026.650938680852, 1053.947302634865, 1057.5346847387125, 1061.2889364126054, 1069.4919913041242, 675.601395176848, 683.3337495941418, 684.1789852177407, 694.3230350408312, 703.0508813102373, 10433.32183408076, 10433.739156521147, 10620.946895265473, 10656.733581660366, 10906.400147991937, 678.0845858091537, 683.1699485125596, 684.1140722764032, 689.3830022130265, 700.264722041854, 5462.890742185134, 5482.424345443751, 6093.086037094471, 6330.715117819709, 6341.651209183539, 104032.0, 106550.0, 106781.0, 106940.0, 112147.43926280316, 90219.54890225508, 91998.54000730088, 93341.0, 97772.0, 97842.5107874456, 84577.0, 86700.0, 87456.81357965975, 88126.0, 91246.54376728075, 12700.174488658207, 12761.553345632858, 12761.680957975754, 12876.806847897102, 12878.0, 39156.412653809646, 39959.80020099881, 40187.3971890416, 40366.1926761469, 40591.79704101461, 67277.66361168359, 67731.66134169298, 69511.65244173747, 71657.6417117911, 72947.27052729405, 14088.718225635635, 17985.19066641978, 18110.366137185134, 18159.36442224516, 18325.725114123565, 18891.811081889005, 19091.809081909003, 20521.589568208852, 21476.140954362276, 21522.461938451663, 18712.906435467736, 18904.14931328121, 19038.904805475882, 19102.235910563977, 19182.616347673247, 18450.723239151153, 18667.15997780076, 18783.53041173927, 18801.529961751065, 19529.8047019528, 18274.90862545679, 18331.0, 18350.35773747966, 18449.169787359868, 18733.71899421537, 12805.935970320088, 13134.343282835629, 13281.800772988607, 13827.170369777443, 13991.370388332345, 14000.859991400363, 14195.716085678023, 14753.778693319824, 15012.924935375253, 15013.6997260052], "value": 9187.66516090556, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1485.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 107.0, 100.0, 106.0, 106.0, 110.0, 107.0, 97.0, 106.0, 109.0], "value": 104.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 149.0, 173.0, 125.0, 144.0, 140.0, 116.0, 169.0, 121.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 161.0, 162.0, 163.0, 163.0, 163.0, 164.0, 166.0, 164.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 74.0, 168.0, 77.0, 74.0, 74.0, 74.0, 165.0, 75.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 129.0, 79.0, 79.0, 79.0, 127.0, 80.0, 79.0, 129.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 116.0, 115.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 148.0, 147.0, 148.0, 148.0, 147.0, 149.0, 150.0, 149.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 52.0, 53.0, 53.0, 52.0, 51.0, 51.0, 63.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 37.0, 38.0, 38.0, 39.0, 38.0, 38.0, 38.0, 38.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 74.0, 75.0, 76.0, 73.0, 74.0, 75.0, 78.0, 73.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 125.0, 119.0, 118.0, 122.0, 128.0, 124.0, 127.0, 116.0, 159.0], "value": 125.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 175.0, 172.0, 181.0, 175.0, 166.0, 173.0, 190.0, 185.0], "value": 176.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 74.0, 68.0, 63.0, 66.0, 63.0, 65.0, 64.0, 65.0], "value": 65.8, "unit": "score"}]}]} 16:21:39 INFO - Return code: 0 16:21:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:21:40 INFO - # TBPL SUCCESS # 16:21:40 INFO - Running post-action listener: _package_coverage_data 16:21:40 INFO - Running post-action listener: _resource_record_post_action 16:21:40 INFO - [mozharness: 2017-09-05 23:21:40.009680Z] Finished run-tests step (success) 16:21:40 INFO - Running post-run listener: _resource_record_post_run 16:21:40 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 40960; Write bytes: 405266432; Read time: 44; Write time: 223684 16:21:40 INFO - TinderboxPrint: CPU usage
13.1% 16:21:40 INFO - TinderboxPrint: I/O read bytes / time
40,960 / 44 16:21:40 INFO - TinderboxPrint: I/O write bytes / time
405,266,432 / 223,684 16:21:40 INFO - TinderboxPrint: CPU idle
8,216.6 (86.9%) 16:21:40 INFO - TinderboxPrint: CPU user
1,190.5 (12.6%) 16:21:40 INFO - TinderboxPrint: Swap in / out
0 / 0 16:21:40 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110764032; Read time: 0; Write time: 69788 16:21:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:21:40 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 40960; Write bytes: 285360128; Read time: 44; Write time: 153152 16:21:40 INFO - Running post-run listener: _upload_blobber_files 16:21:40 INFO - Blob upload gear active. 16:21:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:21:40 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 16:21:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:21:41 INFO - (blobuploader) - INFO - Open directory for files ... 16:21:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:21:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:21:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:47 INFO - (blobuploader) - INFO - Done attempting. 16:21:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:21:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:21:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:48 INFO - (blobuploader) - INFO - Done attempting. 16:21:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:21:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:21:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:49 INFO - (blobuploader) - INFO - Done attempting. 16:21:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:21:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:21:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:50 INFO - (blobuploader) - INFO - Done attempting. 16:21:50 INFO - (blobuploader) - INFO - Iteration through files over. 16:21:50 INFO - Return code: 0 16:21:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:21:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:21:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/055ead05291cacfa9824a0fa7e35087f2ed8b328affac6bcd359dab8163d9b89bbce263a53674c100845905c0522eb7348232850d3d7f4c0d1a43dcea6473c77", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d0f5e046e10b3baeffafc6c7555f98c79aaf2fa7e5b07b95ae36aeb4fb4d9bd495fb68645e714e25b8bd1cef70673d3739e049c126a1da7c85edc1df1acf474", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58c776a7c8228828f44ac3db562c40be65374e4b61bd323462d784ccff5bac318c0d9881251791c22d2b1555fe40d5c320f2ed12ebb1bdf77c390e2806e7b0f8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e74202a156cb61ac9488286764f324c13f60d504ff9414640c5796c67472d2f51da4385467c94bb70f6e7eacd3e617237e1cacd2a1a264125e78d2a9897e744"} 16:21:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:21:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:21:50 INFO - Contents: 16:21:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/055ead05291cacfa9824a0fa7e35087f2ed8b328affac6bcd359dab8163d9b89bbce263a53674c100845905c0522eb7348232850d3d7f4c0d1a43dcea6473c77", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d0f5e046e10b3baeffafc6c7555f98c79aaf2fa7e5b07b95ae36aeb4fb4d9bd495fb68645e714e25b8bd1cef70673d3739e049c126a1da7c85edc1df1acf474", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58c776a7c8228828f44ac3db562c40be65374e4b61bd323462d784ccff5bac318c0d9881251791c22d2b1555fe40d5c320f2ed12ebb1bdf77c390e2806e7b0f8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e74202a156cb61ac9488286764f324c13f60d504ff9414640c5796c67472d2f51da4385467c94bb70f6e7eacd3e617237e1cacd2a1a264125e78d2a9897e744"} 16:21:50 INFO - Running post-run listener: copy_logs_to_upload_dir 16:21:50 INFO - Copying logs to upload dir... 16:21:50 INFO - mkdir: /builds/slave/test/build/upload/logs 16:21:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.704167 ========= master_lag: -0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-05 16:21:50.821744) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:21:50.826804) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/055ead05291cacfa9824a0fa7e35087f2ed8b328affac6bcd359dab8163d9b89bbce263a53674c100845905c0522eb7348232850d3d7f4c0d1a43dcea6473c77", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d0f5e046e10b3baeffafc6c7555f98c79aaf2fa7e5b07b95ae36aeb4fb4d9bd495fb68645e714e25b8bd1cef70673d3739e049c126a1da7c85edc1df1acf474", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58c776a7c8228828f44ac3db562c40be65374e4b61bd323462d784ccff5bac318c0d9881251791c22d2b1555fe40d5c320f2ed12ebb1bdf77c390e2806e7b0f8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e74202a156cb61ac9488286764f324c13f60d504ff9414640c5796c67472d2f51da4385467c94bb70f6e7eacd3e617237e1cacd2a1a264125e78d2a9897e744"} build_url:https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011639 build_url: 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/055ead05291cacfa9824a0fa7e35087f2ed8b328affac6bcd359dab8163d9b89bbce263a53674c100845905c0522eb7348232850d3d7f4c0d1a43dcea6473c77", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d0f5e046e10b3baeffafc6c7555f98c79aaf2fa7e5b07b95ae36aeb4fb4d9bd495fb68645e714e25b8bd1cef70673d3739e049c126a1da7c85edc1df1acf474", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58c776a7c8228828f44ac3db562c40be65374e4b61bd323462d784ccff5bac318c0d9881251791c22d2b1555fe40d5c320f2ed12ebb1bdf77c390e2806e7b0f8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e74202a156cb61ac9488286764f324c13f60d504ff9414640c5796c67472d2f51da4385467c94bb70f6e7eacd3e617237e1cacd2a1a264125e78d2a9897e744"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:21:50.855378) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:21:50.855698) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504652461.194392-865497825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004572 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:21:50.941529) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:21:50.941839) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:21:50.942137) ========= ========= Total master_lag: 0.06 =========