builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-081 starttime: 1506003154.24 results: success (0) revision: f7e9777221a34f9f23c2e4933307eb38b621b679 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:34.237826) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:34.238288) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:34.238591) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:34.279149) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:34.279459) ========= 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=483e410453545d07cca6935c000001ab-1506002765.801343-1765993885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 07:12:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 93.6M=0s 2017-09-21 07:12:34 (93.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121630 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:34.555195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:34.555641) ========= 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=483e410453545d07cca6935c000001ab-1506002765.801343-1765993885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018872 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:12:34.598571) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 30 secs) (at 2017-09-21 07:12:34.598875) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f7e9777221a34f9f23c2e4933307eb38b621b679 --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 f7e9777221a34f9f23c2e4933307eb38b621b679 --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=483e410453545d07cca6935c000001ab-1506002765.801343-1765993885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 07:12:34,655 truncating revision to first 12 chars 2017-09-21 07:12:34,655 Setting DEBUG logging. 2017-09-21 07:12:34,656 attempt 1/10 2017-09-21 07:12:34,656 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f7e9777221a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 07:12:35,653 attempt 1/10 2017-09-21 07:12:36,314 current task status: no status available at this point. state: PENDING 2017-09-21 07:12:36,314 sleeping for 11.00s (attempt 1/10) 2017-09-21 07:12:47,326 attempt 2/10 2017-09-21 07:12:47,906 current task status: no status available at this point. state: PENDING 2017-09-21 07:12:47,906 sleeping for 15.50s (attempt 2/10) 2017-09-21 07:13:03,422 attempt 3/10 2017-09-21 07:13:03,978 current task status: no status available at this point. state: PENDING 2017-09-21 07:13:03,978 sleeping for 24.25s (attempt 3/10) 2017-09-21 07:13:28,253 attempt 4/10 2017-09-21 07:13:28,795 current task status: no status available at this point. state: STARTED 2017-09-21 07:13:28,795 sleeping for 35.38s (attempt 4/10) 2017-09-21 07:14:04,205 attempt 5/10 2017-09-21 07:14:04,539 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-21 07:14:04,919 unpacking tar archive at: mozilla-central-f7e9777221a3/testing/mozharness/ program finished with exit code 0 elapsedTime=90.547198 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 30 secs) (at 2017-09-21 07:14:05.206323) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 07:14:05.206612) ========= script_repo_revision: f7e9777221a34f9f23c2e4933307eb38b621b679 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 07:14:05.206988) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 07:14:05.207308) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 07:14:05.232614) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-21 07:14:05.232874) ========= /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=483e410453545d07cca6935c000001ab-1506002765.801343-1765993885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:14:05 INFO - MultiFileLogger online at 20170921 07:14:05 in /builds/slave/test 07:14:05 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 07:14:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:14:05 INFO - {'append_to_log': False, 07:14:05 INFO - 'base_work_dir': '/builds/slave/test', 07:14:05 INFO - 'blob_upload_branch': 'Firefox', 07:14:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:14:05 INFO - 'branch': 'Firefox', 07:14:05 INFO - 'buildbot_json_path': 'buildprops.json', 07:14:05 INFO - 'code_coverage': False, 07:14:05 INFO - 'config_files': ('talos/linux_config.py',), 07:14:05 INFO - 'default_actions': ('clobber', 07:14:05 INFO - 'read-buildbot-config', 07:14:05 INFO - 'download-and-extract', 07:14:05 INFO - 'populate-webroot', 07:14:05 INFO - 'create-virtualenv', 07:14:05 INFO - 'install', 07:14:05 INFO - 'setup-mitmproxy', 07:14:05 INFO - 'run-tests'), 07:14:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:14:05 INFO - 'disable_ccov_upload': False, 07:14:05 INFO - 'disable_stylo': False, 07:14:05 INFO - 'download_minidump_stackwalk': True, 07:14:05 INFO - 'download_symbols': 'ondemand', 07:14:05 INFO - 'enable_stylo': False, 07:14:05 INFO - 'enable_webrender': False, 07:14:05 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 07:14:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:14:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:14:05 INFO - 'gecko_profile': False, 07:14:05 INFO - 'gecko_profile_interval': 0, 07:14:05 INFO - 'installer_path': 'installer.exe', 07:14:05 INFO - 'log_level': 'info', 07:14:05 INFO - 'log_name': 'talos', 07:14:05 INFO - 'log_to_console': True, 07:14:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:14:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:14:05 INFO - 'opt_config_files': (), 07:14:05 INFO - 'pip_index': False, 07:14:05 INFO - 'suite': 'dromaeojs-e10s', 07:14:05 INFO - 'system_bits': '32', 07:14:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:14:05 INFO - 'title': 'talos-linux64-ix-081', 07:14:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:14:05 INFO - 'use_talos_json': True, 07:14:05 INFO - 'verify': 'False', 07:14:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:14:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:14:05 INFO - 'work_dir': 'build'} 07:14:05 INFO - [mozharness: 2017-09-21 14:14:05.432426Z] Running clobber step. 07:14:05 INFO - Running pre-action listener: _resource_record_pre_action 07:14:05 INFO - Running main action method: clobber 07:14:05 INFO - rmtree: /builds/slave/test/build 07:14:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:14:06 INFO - Running post-action listener: _resource_record_post_action 07:14:06 INFO - [mozharness: 2017-09-21 14:14:06.436838Z] Finished clobber step (success) 07:14:06 INFO - [mozharness: 2017-09-21 14:14:06.436935Z] Running read-buildbot-config step. 07:14:06 INFO - Running pre-action listener: _resource_record_pre_action 07:14:06 INFO - Running main action method: read_buildbot_config 07:14:06 INFO - Using buildbot properties: 07:14:06 INFO - { 07:14:06 INFO - "project": "", 07:14:06 INFO - "product": "firefox", 07:14:06 INFO - "who": "archaeopteryx@coole-files.de", 07:14:06 INFO - "installer_path": "public/build/target.tar.bz2", 07:14:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 07:14:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 07:14:06 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 07:14:06 INFO - "stage_platform": "linux64-qr", 07:14:06 INFO - "basedir": "/builds/slave/test", 07:14:06 INFO - "buildnumber": 68, 07:14:06 INFO - "platform": "ubuntu64_hw_qr", 07:14:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:14:06 INFO - "slavebuilddir": "test", 07:14:06 INFO - "taskId": "TV_jKqDHTf2w22Qwj7Qrxw", 07:14:06 INFO - "branch": "mozilla-central", 07:14:06 INFO - "script_repo_revision": "production", 07:14:06 INFO - "revision": "f7e9777221a34f9f23c2e4933307eb38b621b679", 07:14:06 INFO - "slavename": "talos-linux64-ix-081", 07:14:06 INFO - "repo_path": "mozilla-central" 07:14:06 INFO - } 07:14:06 INFO - Finding installer, test and symbols from parent task. 07:14:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TV_jKqDHTf2w22Qwj7Qrxw'}, attempt #1 07:14:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TV_jKqDHTf2w22Qwj7Qrxw'}, attempt #1 07:14:07 INFO - Task dependencies: OYLYgPYVTVKEo2gH9bptQA 07:14:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA'}, attempt #1 07:14:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 07:14:08 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.test_packages.json 07:14:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.crashreporter-symbols.zip 07:14:08 INFO - Running post-action listener: _resource_record_post_action 07:14:08 INFO - [mozharness: 2017-09-21 14:14:08.140104Z] Finished read-buildbot-config step (success) 07:14:08 INFO - [mozharness: 2017-09-21 14:14:08.140325Z] Running download-and-extract step. 07:14:08 INFO - Running pre-action listener: _resource_record_pre_action 07:14:08 INFO - Running main action method: download_and_extract 07:14:08 INFO - mkdir: /builds/slave/test/build/tests 07:14:08 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')]} 07:14:08 INFO - trying https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.test_packages.json 07:14:08 INFO - Downloading https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:14:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:14:08 INFO - Downloaded 989 bytes. 07:14:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:14:08 INFO - Using the following test package requirements: 07:14:08 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:14:08 INFO - u'common': [u'target.common.tests.zip'], 07:14:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:14:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:14:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:14:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:14:08 INFO - u'mozbase': [u'target.common.tests.zip'], 07:14:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:14:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:14:08 INFO - u'web-platform': [u'target.common.tests.zip', 07:14:08 INFO - u'target.web-platform.tests.tar.gz'], 07:14:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:14:08 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 07:14:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.common.tests.zip 07:14:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:14:08 INFO - Fetch https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.common.tests.zip into memory 07:14:09 INFO - Content-Length response header: 54741111 07:14:09 INFO - Bytes received: 54741111 07:14:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.talos.tests.zip 07:14:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:14:20 INFO - Fetch https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.talos.tests.zip into memory 07:14:22 INFO - Content-Length response header: 14509184 07:14:22 INFO - Bytes received: 14509184 07:14:22 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')]} 07:14:22 INFO - trying https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 07:14:22 INFO - Downloading https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:14:23 INFO - Downloaded 61718892 bytes. 07:14:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 07:14:23 INFO - mkdir: /builds/slave/test/properties 07:14:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:14:23 INFO - Writing to file /builds/slave/test/properties/build_url 07:14:23 INFO - Contents: 07:14:23 INFO - build_url:https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 07:14:23 INFO - Running post-action listener: _resource_record_post_action 07:14:23 INFO - Running post-action listener: find_tests_for_verification 07:14:23 INFO - Running post-action listener: set_extra_try_arguments 07:14:23 INFO - [mozharness: 2017-09-21 14:14:23.761749Z] Finished download-and-extract step (success) 07:14:23 INFO - [mozharness: 2017-09-21 14:14:23.761847Z] Running populate-webroot step. 07:14:23 INFO - Running pre-action listener: _resource_record_pre_action 07:14:23 INFO - Running main action method: populate_webroot 07:14:23 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:14:23 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['tresize', 07:14:23 INFO - 'tcanvasmark']}, 07:14:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:14:23 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['dromaeo_css', 07:14:23 INFO - 'kraken']}, 07:14:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:14:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:14:23 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:14:23 INFO - 'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:14:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:14:23 INFO - 'tests': ['damp', 'tps']}, 07:14:23 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:14:23 INFO - 'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['damp', 'tps']}, 07:14:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:14:23 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['dromaeo_dom']}, 07:14:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:14:23 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['basic_compositor_video', 07:14:23 INFO - 'glvideo']}, 07:14:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:14:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:14:23 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:14:23 INFO - 'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['ts_paint_webext', 07:14:23 INFO - 'tp5o_webext']}, 07:14:23 INFO - 'other-e10s': {'tests': ['a11yr', 07:14:23 INFO - 'ts_paint', 07:14:23 INFO - 'tpaint', 07:14:23 INFO - 'sessionrestore', 07:14:23 INFO - 'sessionrestore_many_windows', 07:14:23 INFO - 'sessionrestore_no_auto_restore', 07:14:23 INFO - 'tabpaint']}, 07:14:23 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['a11yr', 07:14:23 INFO - 'ts_paint', 07:14:23 INFO - 'tpaint', 07:14:23 INFO - 'sessionrestore', 07:14:23 INFO - 'sessionrestore_many_windows', 07:14:23 INFO - 'sessionrestore_no_auto_restore', 07:14:23 INFO - 'tabpaint']}, 07:14:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 07:14:23 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 07:14:23 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['perf_reftest_singletons']}, 07:14:23 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['bloom_basic']}, 07:14:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:14:23 INFO - 'tsvgr_opacity', 07:14:23 INFO - 'tart', 07:14:23 INFO - 'tscrollx', 07:14:23 INFO - 'tsvg_static']}, 07:14:23 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['tsvgx', 07:14:23 INFO - 'tsvgr_opacity', 07:14:23 INFO - 'tart', 07:14:23 INFO - 'tscrollx', 07:14:23 INFO - 'tsvg_static']}, 07:14:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:14:23 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:14:23 INFO - 'talos_options': ['--disable-stylo'], 07:14:23 INFO - 'tests': ['tp5o']}, 07:14:23 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:14:23 INFO - 'talos_options': ['--mitmproxy', 07:14:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:14:23 INFO - '--firstNonBlankPaint'], 07:14:23 INFO - 'tests': ['tp6_google', 07:14:23 INFO - 'tp6_youtube', 07:14:23 INFO - 'tp6_amazon', 07:14:23 INFO - 'tp6_facebook']}, 07:14:23 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:14:23 INFO - 'talos_options': ['--disable-stylo', 07:14:23 INFO - '--mitmproxy', 07:14:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:14:23 INFO - '--firstNonBlankPaint'], 07:14:23 INFO - 'tests': ['tp6_google', 07:14:23 INFO - 'tp6_youtube', 07:14:23 INFO - 'tp6_amazon', 07:14:23 INFO - 'tp6_facebook']}, 07:14:23 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:14:23 INFO - 'talos_options': ['--stylo-threads=1', 07:14:23 INFO - '--mitmproxy', 07:14:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:14:23 INFO - '--firstNonBlankPaint'], 07:14:23 INFO - 'tests': ['tp6_google', 07:14:23 INFO - 'tp6_youtube', 07:14:23 INFO - 'tp6_amazon', 07:14:23 INFO - 'tp6_facebook']}, 07:14:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:14:23 INFO - 'talos_options': ['--xperf_path', 07:14:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:14:23 INFO - 'tests': ['tp5n']}, 07:14:23 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:14:23 INFO - 'talos_options': ['--disable-stylo', 07:14:23 INFO - '--xperf_path', 07:14:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:14:23 INFO - 'tests': ['tp5n']}}} 07:14:23 INFO - Running post-action listener: _resource_record_post_action 07:14:23 INFO - [mozharness: 2017-09-21 14:14:23.777252Z] Finished populate-webroot step (success) 07:14:23 INFO - [mozharness: 2017-09-21 14:14:23.777341Z] Running create-virtualenv step. 07:14:23 INFO - Running pre-action listener: _resource_record_pre_action 07:14:23 INFO - Running main action method: create_virtualenv 07:14:23 INFO - Creating virtualenv /builds/slave/test/build/venv 07:14:23 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:14:23 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 07:14:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:14:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:14:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:14:24 INFO - Installing distribute.............................................................................................................................................................................................done. 07:14:24 INFO - Installing pip...............done. 07:14:24 INFO - Return code: 0 07:14:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:14:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:14:24 INFO - Reading from file tmpfile_stdout 07:14:24 INFO - Output received: 07:14:24 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 07:14:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:14:24 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')]} 07:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:24 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')]} 07:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:24 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 0x15c61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1932a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, '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': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', '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 07:14:24 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 07:14:24 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 07:14:24 INFO - Using env: {'DISPLAY': ':0', 07:14:24 INFO - 'HOME': '/home/cltbld', 07:14:24 INFO - 'LANG': 'en_US.UTF-8', 07:14:24 INFO - 'LANGUAGE': 'en_US:en', 07:14:24 INFO - 'LOGNAME': 'cltbld', 07:14:24 INFO - 'MAIL': '/var/mail/cltbld', 07:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:24 INFO - 'MOZ_NO_REMOTE': '1', 07:14:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:24 INFO - 'NO_EM_RESTART': '1', 07:14:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:24 INFO - 'PWD': '/builds/slave/test', 07:14:24 INFO - 'SHELL': '/bin/bash', 07:14:24 INFO - 'SHLVL': '1', 07:14:24 INFO - 'TERM': 'linux', 07:14:24 INFO - 'TMOUT': '86400', 07:14:24 INFO - 'USER': 'cltbld', 07:14:24 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:24 INFO - '_': '/tools/buildbot/bin/python'} 07:14:26 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:14:26 INFO - Downloading/unpacking pip>=1.5 07:14:26 INFO - Running setup.py egg_info for package pip 07:14:26 INFO - warning: no files found matching 'pip/cacert.pem' 07:14:26 INFO - warning: no files found matching '*.html' under directory 'docs' 07:14:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:14:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:14:26 INFO - Installing collected packages: pip 07:14:26 INFO - Found existing installation: pip 1.1 07:14:26 INFO - Uninstalling pip: 07:14:26 INFO - Successfully uninstalled pip 07:14:26 INFO - Running setup.py install for pip 07:14:26 INFO - warning: no files found matching 'pip/cacert.pem' 07:14:26 INFO - warning: no files found matching '*.html' under directory 'docs' 07:14:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:14:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:14:26 INFO - Installing pip script to /builds/slave/test/build/venv/bin 07:14:26 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 07:14:26 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 07:14:26 INFO - Successfully installed pip 07:14:26 INFO - Cleaning up... 07:14:26 INFO - Return code: 0 07:14:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:26 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 0x15c61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1932a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, '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': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', '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 07:14:26 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 07:14:26 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 07:14:26 INFO - Using env: {'DISPLAY': ':0', 07:14:26 INFO - 'HOME': '/home/cltbld', 07:14:26 INFO - 'LANG': 'en_US.UTF-8', 07:14:26 INFO - 'LANGUAGE': 'en_US:en', 07:14:26 INFO - 'LOGNAME': 'cltbld', 07:14:26 INFO - 'MAIL': '/var/mail/cltbld', 07:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:26 INFO - 'MOZ_NO_REMOTE': '1', 07:14:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:26 INFO - 'NO_EM_RESTART': '1', 07:14:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:26 INFO - 'PWD': '/builds/slave/test', 07:14:26 INFO - 'SHELL': '/bin/bash', 07:14:26 INFO - 'SHLVL': '1', 07:14:26 INFO - 'TERM': 'linux', 07:14:26 INFO - 'TMOUT': '86400', 07:14:26 INFO - 'USER': 'cltbld', 07:14:26 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:26 INFO - '_': '/tools/buildbot/bin/python'} 07:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:26 INFO - Downloading/unpacking psutil>=3.1.1 07:14:26 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 07:14:26 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 07:14:26 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 07:14:26 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 07:14:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:14:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:14:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:14:27 INFO - Installing collected packages: psutil 07:14:27 INFO - Running setup.py install for psutil 07:14:27 INFO - building 'psutil._psutil_linux' extension 07:14:27 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 07:14:27 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 07:14:27 INFO - building 'psutil._psutil_posix' extension 07:14:27 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 07:14:27 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 07:14:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:14:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:14:27 INFO - Successfully installed psutil 07:14:27 INFO - Cleaning up... 07:14:28 INFO - Return code: 0 07:14:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:14:28 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')]} 07:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:28 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')]} 07:14:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:28 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 0x15c61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1932a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, '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': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', '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 07:14:28 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 07:14:28 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 07:14:28 INFO - Using env: {'DISPLAY': ':0', 07:14:28 INFO - 'HOME': '/home/cltbld', 07:14:28 INFO - 'LANG': 'en_US.UTF-8', 07:14:28 INFO - 'LANGUAGE': 'en_US:en', 07:14:28 INFO - 'LOGNAME': 'cltbld', 07:14:28 INFO - 'MAIL': '/var/mail/cltbld', 07:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:28 INFO - 'MOZ_NO_REMOTE': '1', 07:14:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:28 INFO - 'NO_EM_RESTART': '1', 07:14:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:28 INFO - 'PWD': '/builds/slave/test', 07:14:28 INFO - 'SHELL': '/bin/bash', 07:14:28 INFO - 'SHLVL': '1', 07:14:28 INFO - 'TERM': 'linux', 07:14:28 INFO - 'TMOUT': '86400', 07:14:28 INFO - 'USER': 'cltbld', 07:14:28 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:28 INFO - '_': '/tools/buildbot/bin/python'} 07:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:14:28 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 07:14:28 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 07:14:28 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 07:14:28 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 07:14:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:14:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:14:29 INFO - Installing collected packages: mozsystemmonitor 07:14:29 INFO - Running setup.py install for mozsystemmonitor 07:14:29 INFO - Successfully installed mozsystemmonitor 07:14:29 INFO - Cleaning up... 07:14:30 INFO - Return code: 0 07:14:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:30 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 0x15c61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1932a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, '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': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', '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 07:14:30 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 07:14:30 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 07:14:30 INFO - Using env: {'DISPLAY': ':0', 07:14:30 INFO - 'HOME': '/home/cltbld', 07:14:30 INFO - 'LANG': 'en_US.UTF-8', 07:14:30 INFO - 'LANGUAGE': 'en_US:en', 07:14:30 INFO - 'LOGNAME': 'cltbld', 07:14:30 INFO - 'MAIL': '/var/mail/cltbld', 07:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:30 INFO - 'MOZ_NO_REMOTE': '1', 07:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:30 INFO - 'NO_EM_RESTART': '1', 07:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:30 INFO - 'PWD': '/builds/slave/test', 07:14:30 INFO - 'SHELL': '/bin/bash', 07:14:30 INFO - 'SHLVL': '1', 07:14:30 INFO - 'TERM': 'linux', 07:14:30 INFO - 'TMOUT': '86400', 07:14:30 INFO - 'USER': 'cltbld', 07:14:30 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:30 INFO - '_': '/tools/buildbot/bin/python'} 07:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:30 INFO - Downloading/unpacking jsonschema==2.5.1 07:14:30 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 07:14:30 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 07:14:30 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 07:14:30 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 07:14:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:14:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:14:32 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 07:14:32 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 07:14:32 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 07:14:32 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 07:14:32 INFO - Downloading functools32-3.2.3-2.tar.gz 07:14:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:14:32 INFO - warning: no files found matching '*.txt' 07:14:32 INFO - no previously-included directories found matching 'build' 07:14:32 INFO - no previously-included directories found matching 'dist' 07:14:32 INFO - no previously-included directories found matching '.git*' 07:14:32 INFO - Installing collected packages: jsonschema, functools32 07:14:32 INFO - Running setup.py install for functools32 07:14:32 INFO - warning: no files found matching '*.txt' 07:14:32 INFO - no previously-included directories found matching 'build' 07:14:32 INFO - no previously-included directories found matching 'dist' 07:14:32 INFO - no previously-included directories found matching '.git*' 07:14:32 INFO - Successfully installed jsonschema functools32 07:14:32 INFO - Cleaning up... 07:14:32 INFO - Return code: 0 07:14:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:14:32 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')]} 07:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:32 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')]} 07:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:32 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 0x15c61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1932a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, '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': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', '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 07:14:32 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 07:14:32 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 07:14:32 INFO - Using env: {'DISPLAY': ':0', 07:14:32 INFO - 'HOME': '/home/cltbld', 07:14:32 INFO - 'LANG': 'en_US.UTF-8', 07:14:32 INFO - 'LANGUAGE': 'en_US:en', 07:14:32 INFO - 'LOGNAME': 'cltbld', 07:14:32 INFO - 'MAIL': '/var/mail/cltbld', 07:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:32 INFO - 'MOZ_NO_REMOTE': '1', 07:14:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:32 INFO - 'NO_EM_RESTART': '1', 07:14:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:32 INFO - 'PWD': '/builds/slave/test', 07:14:32 INFO - 'SHELL': '/bin/bash', 07:14:32 INFO - 'SHLVL': '1', 07:14:32 INFO - 'TERM': 'linux', 07:14:32 INFO - 'TMOUT': '86400', 07:14:32 INFO - 'USER': 'cltbld', 07:14:32 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:32 INFO - '_': '/tools/buildbot/bin/python'} 07:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:14:32 INFO - Cleaning up... 07:14:32 INFO - Return code: 0 07:14:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:14:32 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')]} 07:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:32 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')]} 07:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:32 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 0x15c61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1932a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, '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': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', '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 07:14:32 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 07:14:32 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 07:14:32 INFO - Using env: {'DISPLAY': ':0', 07:14:32 INFO - 'HOME': '/home/cltbld', 07:14:32 INFO - 'LANG': 'en_US.UTF-8', 07:14:32 INFO - 'LANGUAGE': 'en_US:en', 07:14:32 INFO - 'LOGNAME': 'cltbld', 07:14:32 INFO - 'MAIL': '/var/mail/cltbld', 07:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:32 INFO - 'MOZ_NO_REMOTE': '1', 07:14:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:32 INFO - 'NO_EM_RESTART': '1', 07:14:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:32 INFO - 'PWD': '/builds/slave/test', 07:14:32 INFO - 'SHELL': '/bin/bash', 07:14:32 INFO - 'SHLVL': '1', 07:14:32 INFO - 'TERM': 'linux', 07:14:32 INFO - 'TMOUT': '86400', 07:14:32 INFO - 'USER': 'cltbld', 07:14:32 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:32 INFO - '_': '/tools/buildbot/bin/python'} 07:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:32 INFO - Downloading/unpacking blobuploader==1.2.4 07:14:32 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 07:14:32 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 07:14:32 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 07:14:32 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 07:14:33 INFO - Downloading blobuploader-1.2.4.tar.gz 07:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:14:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:14:34 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 07:14:34 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 07:14:34 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 07:14:34 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 07:14:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:14:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:14:34 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 07:14:34 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 07:14:34 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 07:14:34 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 07:14:34 INFO - Downloading docopt-0.6.1.tar.gz 07:14:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:14:34 INFO - Installing collected packages: blobuploader, requests, docopt 07:14:34 INFO - Running setup.py install for blobuploader 07:14:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:14:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:14:34 INFO - Running setup.py install for requests 07:14:35 INFO - Running setup.py install for docopt 07:14:35 INFO - Successfully installed blobuploader requests docopt 07:14:35 INFO - Cleaning up... 07:14:35 INFO - Return code: 0 07:14:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:14: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')]} 07:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14: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')]} 07:14:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:35 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 0x15c61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1932a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, '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': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', '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 07:14:35 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 07:14:35 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 07:14:35 INFO - Using env: {'DISPLAY': ':0', 07:14:35 INFO - 'HOME': '/home/cltbld', 07:14:35 INFO - 'LANG': 'en_US.UTF-8', 07:14:35 INFO - 'LANGUAGE': 'en_US:en', 07:14:35 INFO - 'LOGNAME': 'cltbld', 07:14:35 INFO - 'MAIL': '/var/mail/cltbld', 07:14:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:35 INFO - 'MOZ_NO_REMOTE': '1', 07:14:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:35 INFO - 'NO_EM_RESTART': '1', 07:14:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:35 INFO - 'PWD': '/builds/slave/test', 07:14:35 INFO - 'SHELL': '/bin/bash', 07:14:35 INFO - 'SHLVL': '1', 07:14:35 INFO - 'TERM': 'linux', 07:14:35 INFO - 'TMOUT': '86400', 07:14:35 INFO - 'USER': 'cltbld', 07:14:35 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:14:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:35 INFO - '_': '/tools/buildbot/bin/python'} 07:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:14:35 INFO - Running setup.py (path:/tmp/pip-99WJzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:14:35 INFO - Running setup.py (path:/tmp/pip-rxaJ2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:14:35 INFO - Running setup.py (path:/tmp/pip-qb9g_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:14:35 INFO - Running setup.py (path:/tmp/pip-1aNuKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:14:35 INFO - Running setup.py (path:/tmp/pip-xNlAIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:14:35 INFO - Running setup.py (path:/tmp/pip-H4n7NQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:14:35 INFO - Running setup.py (path:/tmp/pip-O2CiY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:14:35 INFO - Running setup.py (path:/tmp/pip-g2kv0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:14:35 INFO - Running setup.py (path:/tmp/pip-22U1BT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:14:35 INFO - Running setup.py (path:/tmp/pip-hEciaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:14:36 INFO - Running setup.py (path:/tmp/pip-U9Gtvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:14:36 INFO - Running setup.py (path:/tmp/pip-xiu6Ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:14:36 INFO - Running setup.py (path:/tmp/pip-iebCOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:14:36 INFO - Running setup.py (path:/tmp/pip-psA4uB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:36 INFO - Running setup.py (path:/tmp/pip-hAInnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:14:36 INFO - Running setup.py (path:/tmp/pip-3hNiTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:14:36 INFO - Running setup.py (path:/tmp/pip-GS4e2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:14:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:14:36 INFO - Running setup.py install for manifestparser 07:14:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:14:36 INFO - Running setup.py install for mozcrash 07:14:36 INFO - Running setup.py install for mozdebug 07:14:36 INFO - Running setup.py install for mozdevice 07:14:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:14:36 INFO - Running setup.py install for mozfile 07:14:37 INFO - Running setup.py install for mozhttpd 07:14:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Running setup.py install for mozinfo 07:14:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Running setup.py install for mozInstall 07:14:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Running setup.py install for mozleak 07:14:37 INFO - Running setup.py install for mozlog 07:14:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Running setup.py install for moznetwork 07:14:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Running setup.py install for mozprocess 07:14:37 INFO - Running setup.py install for mozprofile 07:14:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Running setup.py install for mozrunner 07:14:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:14:37 INFO - Running setup.py install for mozscreenshot 07:14:38 INFO - Running setup.py install for moztest 07:14:38 INFO - Running setup.py install for mozversion 07:14:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:14:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:14:38 INFO - Cleaning up... 07:14:38 INFO - Return code: 0 07:14:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:14: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')]} 07:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14: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')]} 07:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:38 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 0x15c61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1932a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, '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': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', '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 07:14:38 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 07:14:38 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 07:14:38 INFO - Using env: {'DISPLAY': ':0', 07:14:38 INFO - 'HOME': '/home/cltbld', 07:14:38 INFO - 'LANG': 'en_US.UTF-8', 07:14:38 INFO - 'LANGUAGE': 'en_US:en', 07:14:38 INFO - 'LOGNAME': 'cltbld', 07:14:38 INFO - 'MAIL': '/var/mail/cltbld', 07:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:38 INFO - 'MOZ_NO_REMOTE': '1', 07:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:38 INFO - 'NO_EM_RESTART': '1', 07:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:38 INFO - 'PWD': '/builds/slave/test', 07:14:38 INFO - 'SHELL': '/bin/bash', 07:14:38 INFO - 'SHLVL': '1', 07:14:38 INFO - 'TERM': 'linux', 07:14:38 INFO - 'TMOUT': '86400', 07:14:38 INFO - 'USER': 'cltbld', 07:14:38 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:38 INFO - '_': '/tools/buildbot/bin/python'} 07:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:14:38 INFO - Running setup.py (path:/tmp/pip-k4iPR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:14:38 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)) 07:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:14:38 INFO - Running setup.py (path:/tmp/pip-jz1q4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:14:38 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)) 07:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:14:38 INFO - Running setup.py (path:/tmp/pip-VIcOAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:14:38 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)) 07:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:14:38 INFO - Running setup.py (path:/tmp/pip-oUQYP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:14:38 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)) 07:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:14:38 INFO - Running setup.py (path:/tmp/pip-kCOX7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:14:38 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)) 07:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:14:38 INFO - Running setup.py (path:/tmp/pip-uHSwzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:14:38 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)) 07:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:14:38 INFO - Running setup.py (path:/tmp/pip-6aL3Ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:14:39 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)) 07:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:14:39 INFO - Running setup.py (path:/tmp/pip-L7x092-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:14:39 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)) 07:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:14:39 INFO - Running setup.py (path:/tmp/pip-WqHocn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:14:39 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)) 07:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:14:39 INFO - Running setup.py (path:/tmp/pip-WANUyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:14:39 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)) 07:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:14:39 INFO - Running setup.py (path:/tmp/pip-lRnilp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:14:39 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)) 07:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:14:39 INFO - Running setup.py (path:/tmp/pip-Ho9w1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:14:39 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)) 07:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:14:39 INFO - Running setup.py (path:/tmp/pip-FKOkJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:14:39 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)) 07:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:14:39 INFO - Running setup.py (path:/tmp/pip-dClAvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:14:39 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)) 07:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:39 INFO - Running setup.py (path:/tmp/pip-wO0tSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:39 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)) 07:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:14:39 INFO - Running setup.py (path:/tmp/pip-5M1Ldb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:14:39 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)) 07:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:14:39 INFO - Running setup.py (path:/tmp/pip-t2UYdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:14:39 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)) 07:14:39 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)) 07:14:39 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)) 07:14:39 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)) 07:14:39 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)) 07:14:39 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)) 07:14:39 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)) 07:14:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:40 INFO - Downloading blessings-1.6.tar.gz 07:14:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:14:40 INFO - Installing collected packages: blessings 07:14:40 INFO - Running setup.py install for blessings 07:14:41 INFO - Successfully installed blessings 07:14:41 INFO - Cleaning up... 07:14:41 INFO - Return code: 0 07:14:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:14:41 INFO - Reading from file tmpfile_stdout 07:14:41 INFO - Current package versions: 07:14:41 INFO - argparse == 1.2.1 07:14:41 INFO - blessings == 1.6 07:14:41 INFO - blobuploader == 1.2.4 07:14:41 INFO - docopt == 0.6.1 07:14:41 INFO - functools32 == 3.2.3-2 07:14:41 INFO - jsonschema == 2.5.1 07:14:41 INFO - manifestparser == 1.1 07:14:41 INFO - mozInstall == 1.13 07:14:41 INFO - mozcrash == 1.0 07:14:41 INFO - mozdebug == 0.1 07:14:41 INFO - mozdevice == 0.51 07:14:41 INFO - mozfile == 1.2 07:14:41 INFO - mozhttpd == 0.7 07:14:41 INFO - mozinfo == 0.10 07:14:41 INFO - mozleak == 0.1 07:14:41 INFO - mozlog == 3.5 07:14:41 INFO - moznetwork == 0.27 07:14:41 INFO - mozprocess == 0.25 07:14:41 INFO - mozprofile == 0.28 07:14:41 INFO - mozrunner == 6.13 07:14:41 INFO - mozscreenshot == 0.1 07:14:41 INFO - mozsystemmonitor == 0.3 07:14:41 INFO - moztest == 0.8 07:14:41 INFO - mozversion == 1.4 07:14:41 INFO - psutil == 3.1.1 07:14:41 INFO - requests == 1.2.3 07:14:41 INFO - wsgiref == 0.1.2 07:14:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:14: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')]} 07:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14: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')]} 07:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:41 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 0x15c61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1932a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, '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': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', '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 07:14:41 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 07:14:41 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 07:14:41 INFO - Using env: {'DISPLAY': ':0', 07:14:41 INFO - 'HOME': '/home/cltbld', 07:14:41 INFO - 'LANG': 'en_US.UTF-8', 07:14:41 INFO - 'LANGUAGE': 'en_US:en', 07:14:41 INFO - 'LOGNAME': 'cltbld', 07:14:41 INFO - 'MAIL': '/var/mail/cltbld', 07:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:41 INFO - 'MOZ_NO_REMOTE': '1', 07:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:41 INFO - 'NO_EM_RESTART': '1', 07:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:41 INFO - 'PWD': '/builds/slave/test', 07:14:41 INFO - 'SHELL': '/bin/bash', 07:14:41 INFO - 'SHLVL': '1', 07:14:41 INFO - 'TERM': 'linux', 07:14:41 INFO - 'TMOUT': '86400', 07:14:41 INFO - 'USER': 'cltbld', 07:14:41 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:41 INFO - '_': '/tools/buildbot/bin/python'} 07:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:41 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)) 07:14:41 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)) 07:14:41 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)) 07:14:41 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)) 07:14:41 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)) 07:14:41 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)) 07:14:41 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)) 07:14:41 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)) 07:14:41 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)) 07:14:41 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:14: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 07:14: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 07:14: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 07:14: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 07:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 07:14:42 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)) 07:14:42 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)) 07:14:42 INFO - Installing collected packages: simplejson 07:14:42 INFO - Running setup.py install for simplejson 07:14:42 INFO - building 'simplejson._speedups' extension 07:14:42 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 07:14:43 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 07:14:43 INFO - Successfully installed simplejson 07:14:43 INFO - Cleaning up... 07:14:43 INFO - Return code: 0 07:14:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1932a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, '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': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', '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 07:14:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:14:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:14:43 INFO - Using env: {'DISPLAY': ':0', 07:14:43 INFO - 'HOME': '/home/cltbld', 07:14:43 INFO - 'LANG': 'en_US.UTF-8', 07:14:43 INFO - 'LANGUAGE': 'en_US:en', 07:14:43 INFO - 'LOGNAME': 'cltbld', 07:14:43 INFO - 'MAIL': '/var/mail/cltbld', 07:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:43 INFO - 'MOZ_NO_REMOTE': '1', 07:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:43 INFO - 'NO_EM_RESTART': '1', 07:14:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:43 INFO - 'PWD': '/builds/slave/test', 07:14:43 INFO - 'SHELL': '/bin/bash', 07:14:43 INFO - 'SHLVL': '1', 07:14:43 INFO - 'TERM': 'linux', 07:14:43 INFO - 'TMOUT': '86400', 07:14:43 INFO - 'USER': 'cltbld', 07:14:43 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:43 INFO - '_': '/tools/buildbot/bin/python'} 07:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:14:43 INFO - Cleaning up... 07:14:43 INFO - Return code: 0 07:14:43 INFO - Running post-action listener: _resource_record_post_action 07:14:43 INFO - Running post-action listener: _start_resource_monitoring 07:14:44 INFO - Starting resource monitoring. 07:14:44 INFO - [mozharness: 2017-09-21 14:14:44.018408Z] Finished create-virtualenv step (success) 07:14:44 INFO - [mozharness: 2017-09-21 14:14:44.018989Z] Running install step. 07:14:44 INFO - Running pre-action listener: _resource_record_pre_action 07:14:44 INFO - Running main action method: install 07:14:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:14:44 INFO - Reading from file tmpfile_stdout 07:14:44 INFO - Detecting whether we're running mozinstall >=1.0... 07:14:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:14:44 INFO - Reading from file tmpfile_stdout 07:14:44 INFO - Output received: 07:14:44 INFO - Usage: mozinstall [options] installer 07:14:44 INFO - Options: 07:14:44 INFO - -h, --help show this help message and exit 07:14:44 INFO - -d DEST, --destination=DEST 07:14:44 INFO - Directory to install application into. [default: 07:14:44 INFO - "/builds/slave/test"] 07:14:44 INFO - --app=APP Application being installed. [default: firefox] 07:14:44 INFO - mkdir: /builds/slave/test/build/application 07:14:44 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'] 07:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:15:02 INFO - Reading from file tmpfile_stdout 07:15:02 INFO - Output received: 07:15:02 INFO - /builds/slave/test/build/application/firefox/firefox 07:15:02 INFO - Running post-action listener: _resource_record_post_action 07:15:02 INFO - [mozharness: 2017-09-21 14:15:02.187858Z] Finished install step (success) 07:15:02 INFO - [mozharness: 2017-09-21 14:15:02.188111Z] Running setup-mitmproxy step. 07:15:02 INFO - Running pre-action listener: _resource_record_pre_action 07:15:02 INFO - Running main action method: setup_mitmproxy 07:15:02 INFO - Skipping: mitmproxy is not required 07:15:02 INFO - Running post-action listener: _resource_record_post_action 07:15:02 INFO - [mozharness: 2017-09-21 14:15:02.189234Z] Finished setup-mitmproxy step (success) 07:15:02 INFO - [mozharness: 2017-09-21 14:15:02.189455Z] Running run-tests step. 07:15:02 INFO - Running pre-action listener: _resource_record_pre_action 07:15:02 INFO - Running pre-action listener: _set_gcov_prefix 07:15:02 INFO - Running main action method: run_tests 07:15:02 WARNING - Try message not found. 07:15:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:15:02 INFO - Python 2.7.3 07:15:02 INFO - Return code: 0 07:15:02 INFO - grabbing minidump binary from tooltool 07:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:15:02 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 0x1936090>, '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 0x1936d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1930f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:15:02 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 07:15:02 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 07:15:02 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 07:15:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:15:02 INFO - Return code: 0 07:15:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:15:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:15:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:15:02 INFO - ENV: RUST_BACKTRACE is now full 07:15:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:15:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:15:02 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/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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 07:15:02 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/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-081 --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 07:15:02 INFO - Using env: {'DISPLAY': ':0', 07:15:02 INFO - 'HOME': '/home/cltbld', 07:15:02 INFO - 'LANG': 'en_US.UTF-8', 07:15:02 INFO - 'LANGUAGE': 'en_US:en', 07:15:02 INFO - 'LOGNAME': 'cltbld', 07:15:02 INFO - 'MAIL': '/var/mail/cltbld', 07:15:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:15:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:15:02 INFO - 'MOZ_ACCELERATED': '1', 07:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:15:02 INFO - 'MOZ_NO_REMOTE': '1', 07:15:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:15:02 INFO - 'MOZ_WEBRENDER': '1', 07:15:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:15:02 INFO - 'NO_EM_RESTART': '1', 07:15:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:15:02 INFO - 'PWD': '/builds/slave/test', 07:15:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:15:02 INFO - 'RUST_BACKTRACE': 'full', 07:15:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:15:02 INFO - 'SHELL': '/bin/bash', 07:15:02 INFO - 'SHLVL': '1', 07:15:02 INFO - 'TERM': 'linux', 07:15:02 INFO - 'TMOUT': '86400', 07:15:02 INFO - 'USER': 'cltbld', 07:15:02 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:15:02 INFO - '_': '/tools/buildbot/bin/python'} 07:15:02 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/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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 07:15:02 INFO - mozversion application_buildid: 20170921132403 07:15:02 INFO - mozversion application_changeset: f7e9777221a34f9f23c2e4933307eb38b621b679 07:15:02 INFO - mozversion application_display_name: Nightly 07:15:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:15:02 INFO - mozversion application_name: Firefox 07:15:02 INFO - mozversion application_remotingname: firefox 07:15:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:15:02 INFO - mozversion application_vendor: Mozilla 07:15:02 INFO - mozversion application_version: 57.0a1 07:15:02 INFO - mozversion platform_buildid: 20170921132403 07:15:02 INFO - mozversion platform_changeset: f7e9777221a34f9f23c2e4933307eb38b621b679 07:15:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:15:02 INFO - mozversion platform_version: 57.0a1 07:15:02 INFO - using testdate: 1506003302 07:15:02 INFO - actual date: 1506003302 07:15:02 INFO - starting webserver on 'localhost:47440' 07:15:02 INFO - SUITE-START | Running 2 tests 07:15:02 INFO - TEST-START | dromaeo_css 07:15:02 INFO - Initialising browser for dromaeo_css test... 07:15:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47440/getInfo.html -profile /tmp/tmpl1kCnt/profile 07:15:02 INFO - TEST-INFO | started process 30409 (/builds/slave/test/build/application/firefox/firefox http://localhost:47440/getInfo.html) 07:15:08 INFO - TEST-INFO | 30409: exit 0 07:15:08 INFO - Browser initialized. 07:15:08 INFO - Running cycle 1/1 for dromaeo_css test... 07:15:08 INFO - Using env: {'DISPLAY': ':0', 07:15:08 INFO - 'HOME': '/home/cltbld', 07:15:08 INFO - 'JSGC_DISABLE_POISONING': '1', 07:15:08 INFO - 'LANG': 'en_US.UTF-8', 07:15:08 INFO - 'LANGUAGE': 'en_US:en', 07:15:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:15:08 INFO - 'LOGNAME': 'cltbld', 07:15:08 INFO - 'MAIL': '/var/mail/cltbld', 07:15:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:15:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:15:08 INFO - 'MOZ_ACCELERATED': '1', 07:15:08 INFO - 'MOZ_CRASHREPORTER': '1', 07:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:15:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:15:08 INFO - 'MOZ_NO_REMOTE': '1', 07:15:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:15:08 INFO - 'MOZ_WEBRENDER': '1', 07:15:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:15:08 INFO - 'NO_EM_RESTART': '1', 07:15:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:15:08 INFO - 'PWD': '/builds/slave/test', 07:15:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:15:08 INFO - 'RUST_BACKTRACE': 'full', 07:15:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:15:08 INFO - 'SHELL': '/bin/bash', 07:15:08 INFO - 'SHLVL': '1', 07:15:08 INFO - 'TERM': 'linux', 07:15:08 INFO - 'TMOUT': '86400', 07:15:08 INFO - 'USER': 'cltbld', 07:15:08 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:15:08 INFO - '_': '/tools/buildbot/bin/python'} 07:15:08 INFO - TEST-INFO | started process 30657 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl1kCnt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:15:09 INFO - PID 30657 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 07:15:09 INFO - PID 30657 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 07:15:10 INFO - PID 30657 | 07:15:10 INFO - PID 30657 | (/builds/slave/test/build/application/firefox/firefox:30716): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:15:10 INFO - PID 30657 | 07:15:20 INFO - PID 30657 | RSS: Main: 211738624 07:15:20 INFO - PID 30657 | 07:18:18 INFO - PID 30657 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47440/tests/dromaeo/cssquery-ext.html) 07:18:19 INFO - PID 30657 | RSS: Main: 213409792 07:18:19 INFO - PID 30657 | 07:21:21 INFO - PID 30657 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-ext.html (next: http://localhost:47440/tests/dromaeo/cssquery-jquery.html) 07:21:22 INFO - PID 30657 | RSS: Main: 211677184 07:21:22 INFO - PID 30657 | 07:24:24 INFO - PID 30657 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47440/tests/dromaeo/cssquery-mootools.html) 07:24:25 INFO - PID 30657 | RSS: Main: 209555456 07:24:25 INFO - PID 30657 | 07:27:27 INFO - PID 30657 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47440/tests/dromaeo/cssquery-prototype.html) 07:27:27 INFO - PID 30657 | RSS: Main: 214081536 07:27:27 INFO - PID 30657 | 07:30:31 INFO - PID 30657 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47440/tests/dromaeo/cssquery-yui.html) 07:30:32 INFO - PID 30657 | RSS: Main: 215351296 07:30:32 INFO - PID 30657 | 07:33:34 INFO - PID 30657 | Cycle 1(1): loaded http://localhost:47440/tests/dromaeo/cssquery-yui.html (next: http://localhost:47440/tests/dromaeo/cssquery-dojo.html) 07:33:34 INFO - PID 30657 | RSS: Main: 215195648 07:33:34 INFO - PID 30657 | 07:33:34 INFO - PID 30657 | __start_tp_report 07:33:34 INFO - PID 30657 | _x_x_mozilla_page_load 07:33:34 INFO - PID 30657 | _x_x_mozilla_page_load_details 07:33:34 INFO - PID 30657 | |i|pagename|runs| 07:33:34 INFO - PID 30657 | |0;dojo.html;3118.0022392834294;3131;3146.5752123463326;3153.7792354535177;3174.2381828361185;6086.878262434749;6260.525012382367;6320.273168585608;6390.5846120002225;6468.967655161718;6255.718492667829;6269.592476489025;6285.497160227183;6294.8111556653375;6316.147320111783;5826.504747096491;5827.854303642401;5829.387914269004;5836.941630583682;5921.023031199847;5878.706064696769;5927.525797936155;5932.288125424955;5966.075258334943;5980.372060933604;5857.85355366115;5878.853028674296;5893.557983151238;5968.4031596840405;5983.850403739898;5673.971630141864;5781.364049954502;5786.363500014995;5862.267216597926;5883.794067207627;5702.201691763156;5793.449622285876;5797.942020579824;5858.794942177046;5867.823965281048;6161.9691901540655;6191.907121393183;6206.6586337751405;6309.779157729503;6349.269833969107;6640;6732.4950628703045;6733.865322693568;6797.01443290725;6847.1783385994;6813.284605116441;6848.520603557753;6859.759908403182;6920.30796920309;6920.342567456134;6325.936740632626;6352.682365881734;6397.6801159941815;6404.967975160094;6408.166938298038;6910.06714093601;6958.79123626292;7013.544119632207;7014.018037474758;7040.823979400454;6617.735290588419;6633.170853643294;6697.397234248941;6734.696938637773;6747.392734653807;6837.38463538274;6854.143232095988;6855.897161542581;6865.897011544831;6868.210155832044;6674.06563081169;6736.528443008942;6736.730530778812;6827.249002609709;6828.726850926007;6540.869182616321;6665.166854143232;6703.631300278434;6818.522703410813;6840.828979275459;5287.9735601321745;5316.149416093406;5324.041672498987;5356.7857285708915;5370.516653501126;4657;4690.554397332248;4692.859214223578;4703.576678098919;4733.077049975222;5733.627314224562;5751.827445176651;5852.648841069549;5858.355580886099;5879.735411906475;6617.900731488935;6811.386975172259;6846.383825455733;6886.449084073262;6957.060796792368;5706.657600543981;5723.055695810144;5731.885362292731;5746.88506229873;5755.820056888328;5700.572457065737;5738.08190689496;5745.339285982082;5748.655080695172;5780.88438231233;6504;6517.055027021055;6575.342465753482;6638.86722265552;6648.966755166193;6853.109095817512;6909.067275917787;6922.376986071277;6934.4452443805385;6939.375456208864;6571.671416429255;6582.473402127914;6585.670716464062;6670.232923213698;6681.365270299314;6554.967225163844;6598.802035938929;6605.372489613479;6699.598024118373;6828.692708828214;6691.5650482718465;6731.259561448334;6736.461083113437;6753.223379311442;6786.898196526958;6476.417122459001;6486.610803351814;6487.610743355413;6543.476521878333;6557.147570815763;6122.846928826637;6369.522285828674;6503.219613646393;6511.511636627177;6655.23464801554;6337.493000560036;6400.807975760735;6484.157059582413;6527.412532872065;6531.3468653134305;6669.966650166913;6695.196576410862;6715.496337774589;6738.460923126237;6742.764003259862;6277.435030847246;6402.391772781578;6431.517636177399;6521.739130434729;6535.052417399545 07:33:34 INFO - PID 30657 | |1;ext.html;872.6334939325483;873.8601823708206;875.1642181716461;881.1232823340773;896.4397251717677;40146.59853401465;41780.79109604455;41867.16265674685;41956.58043419569;41999;37820;38032.80983595078;38035.809820950926;38050.238995220076;38096.42855357172;7221.350078492935;7245.8913116303165;7266.200717921024;7273.163586187603;7277.19950805411;7205;7219.458540609476;7239.927600723978;7269.527480713763;7307.269273072703;7306.452016098788;7311.4516411269105;7313;7338.266173382673;7415.480916335859;7904.4862083964645;8073.677052917877;8080.878786818203;8085.272325490734;8095.069067057246;7919.881201781978;7939.28546430824;7957.681692732283;7989.960050199712;7993.600319983978;38650.22699545993;39253;39313.41029884554;39340;39367.818965431085;3460.653934606544;3487.8430470628628;3487.860485580574;3502.2820321833965;3502.562179727534;31885.681143188736;32083.51874721881;32090.839545802355;32094.358112837846;32109.51835722466;5244.763985620618;5268.341457317835;5287.312649355559;5375.892482150335;5395.433479484637;190525;190845;191071;191410;191665.04167479213;47273.763631181624;47616;47701.284480732815;47746;47797.52202478;49018;49469.752651237235;49657.75171124194;49839;49954;14072.788908166385;14332.426702931974;14354.138751674933;14377;14537.418503259963;11417.086633069335;11418.371989540488;11426.485808138654;11460.083193344512;11494.827577586342;14219.928900355431;14267.42930282777;14343.211123388313;14350.282485875665;14506.782398264035;5479.671219726829;5525.889482210333;5563.888722225533;5644.8871022579315;5698.401667824902;981.6269817469316;991.1822746234385;998.8801343838641;1015.4465816130227;1025.646152077532;22577;22770.43073923132;22802.885985569967;22824.65763013556;23005.769942300696;1076.962306319275;1095.4413249242943;1106.4578356605205;1106.9594581093745;1113.493360520961;11938.164328497096;12023.218490798245;12137.271763694207;12151.254211905652;12250.221109595472;228339;229721;229835;230337;230363;44440.55559444429;45896.39362622359;51596.48403515992;51861.48138518642;51887.74056129695;1412.5479846449039;1414.073781673006;1439.8776104031065;1449.3115770009372;1449.8187726534184;26715.86642066855;27121.321966950985;27364;27484.175474735788;27486.450270994068;37686.434703480016;37836;38137.6186238134;38166;38238.617613824616;195800;195991;196700;197568;199212;32712.83643581767;33765;33885.3222935535;34259;34313.828430857684;41217.73302468273;41842;42286.365704515076;42374;42454.363184551636;31495.84252078725;31830.522542161423;32061.67938320587;32403.51394729128;32431.513527297582;31123;31552.842235788674;31807.04578862638;31977.360452790348;32221.838890805397;35486.11284717903;35666.64333356737;35972.82013589915;35989.28021439609;36055;31518.36963260768;31538;31614.841925790224;31629.209269767518;31749.04752857418;22573.661395078758;22599.661005084607;23077.19229826948;23148.652770208122;23236.883815580815 07:33:34 INFO - PID 30657 | |2;jquery.html;574.1760573576918;574.4227051812927;579.7246308003698;600.5015836855409;606.2391698418483;21648.567028659418;22786.65820012701;22799.202027929026;22803.429914252152;22813.08747650094;21774.237901673445;22002.119915203373;22019.889900550515;22680.54638907221;24705.382365440873;6563.081168636383;6758.1214442122455;6760.121184246041;6762.8309292267595;6775.9661201693925;6611.702473388685;6698.497612679044;6725.226598941135;6741.696623651923;6766.898496522556;6285.308616052231;6622.370874766913;6626.237982632011;6626.469882409419;6630.73477060917;6491.22105347359;6497.902531462032;6531.542792004561;6544.443722283611;6547.541672082956;5978.701064946736;6030.09548567714;6053.818385448443;6234.688265586702;6254.249490061222;23308.65037024446;23334.18330358446;23494.53010939789;23559.175428859908;23591.76408235913;1979.3468155508647;2013.9798602013939;2020.202020202012;2021.4036859126593;2026.7973202679761;18301.7254741179;19004.714929276073;19159.23363065476;19207.135678894345;19222.231110755554;2359.4809141988735;2377.8229776260687;2398.760123987605;2416.7099948006353;2418.516296740659;118436.40781796123;122940;127706.72293277041;131156.34421827926;140079;14136.434542618386;14322.928385358007;14537.200453974921;14549;14719.55841324762;22842.885785570965;23059.769402305763;23105.884470577876;23265.534689306118;23272.767272327397;8878.911210887938;9346.626134954662;9418.10527999839;9444.433333999981;9556.187724043533;8340.916590834015;8377.37169712274;8380.497170169809;8403.579821009047;8407;9056.592453339616;9174.724758257264;9308.20880225175;9325.160735533835;9330.626774929062;2633.9868300658372;2742.3555464465835;2748.2854457841104;2797.5943488194507;2801.957970630442;947.4126041853957;961.2502248246379;970.5632465390603;976.3312131190157;982.3614650477247;18439.631207376045;18476.815231847777;18577.88532688016;18875.716864247046;18992.525186870163;1006.9144122749502;1007.652359935821;1016.6187679620142;1017.9134773544332;1022.4070039377143;10389.168866490663;10396.324238924446;10502.212334074975;10536;10643;469250;471065;471777;479481;485460;107672;111282;111577;113102.43448782703;113633;1084.5499117866154;1178.3283528388736;1204.1871736577775;1233.4018001269378;1248.095131029996;19715.211391544177;19949.800501994796;20070.09684564169;20151.496212594804;20232.291869784487;59180.70409647924;61469;61710;61908;63089.36910631019;80847.5957620208;81373;81742.59128704318;82324.17675823165;83211.58394208232;14181.858181418054;14243.287835607971;14405.919556033548;14446.783298250324;14466.927665361605;21660.133594656036;21743.782562174176;21956.451088722908;22232.110715571194;22236.77763222347;17683.823161768218;17780.822191777916;17814.109294534963;17818.19818108162;18123.456296310604;14929.925350373178;15888.443904463751;16111.516654500383;16767.24547395346;16879.662406752042;18031.729524057413;18555.72166417532;18600.720989185444;18638.34765783191;18683.53291167677;14219.928900355431;14228.14631122136;14292.857071429153;14295.285235737963;14310.57068287953;17229.31082756676;17629.471115866545;17744.822551774832;17954.461366159037;17977.460676179737 07:33:34 INFO - PID 30657 | |3;mootools.html;1321.550672771258;1382.578313614348;1400.1179257068045;1406.3952500424814;1406.5639651707977;14991.325390357446;15700.371985120595;15704.764428533581;15721;15751.369945202177;15354.769678454804;15509.146996915166;15515.146666933342;15527;15531.84468155318;2775.3616668166333;2829.740765359416;2858.35686970431;2866.9569956450564;2874.209592362107;2719.3473566344132;2791.148699646607;2799.6360473138566;2814.3808362160294;2817.2534278416238;2656.8007399445023;2811.8734656940487;2821.6896141424427;2921.839298838563;2932.428176505582;2858.213991152429;2890.0318393338257;2894.72500112489;2897.6088228089147;2901.0571564241704;2703.3106557827628;2731.877065532056;2731.9043833465735;2739.7260273972643;2762.6822915364787;15238.161901095435;15253.466128685553;15462.845371546253;15540.689186216212;15546.9222653886;8217.383696222747;8531.786705332353;8548.914510854875;8570.614322355508;8592.14078592142;5264.710440925785;5473.945260547383;5629.887402251973;5640.774369025234;5676.971615141939;1810.4840120565575;1858.9628207435776;1868.6449574580786;1912.531429799694;1925.9614807703908;27426.862865685544;27448;27653.585196222073;27678.584821227698;27791.305217369525;7039.753608623776;7053.506254562234;7100.857982840314;7259.564426134343;7273.16358618755;17959.461216163538;18148.909255453902;18219.08904554767;18239.088045597666;18274.269029238683;8269.503829770232;8370.246677799027;8392.160783921681;8462;8468.830623387497;7214.67533960973;7409.036825212688;7457.888131678029;7484.363829074481;7488.475806693588;5342.91985620216;5564.777408903598;5572.881490604286;5582.692951887685;5639.407862174413;1609.9597510062108;1610.919454027294;1622.9837701622832;1639.5819066138058;1647.8352164783425;757;902.1925376787739;921.8386782313069;931.2503434735104;941.8493041113389;8612.397132200686;8926.910730892609;8952.283817294601;8989.190972812348;9013.504257265913;964.9855252171224;989.8168838764851;997.581015973293;1008.7881544875659;1009.7929924365487;5106.336176297058;5317.30874986257;5341.81303654378;5431.402545719968;5523.033469142884;21552.353429397142;21950.670739938912;22166.88916555407;22331.21840735599;22410.775892241196;20752.273670421462;21533.24633637815;21852.12591496354;21884.12463501442;21972.780272197073;11315.886841131647;11485.966263036367;11780.705482362839;11798.056155507538;11981.041516678644;8169.142240064708;8540.957295213733;8631.26634236097;8736.08271131522;8763.036066032859;18588.628227435645;18755.718664219774;18949.33677321287;19319.806801932362;19669.40991770249;19098.140583674045;19816.504587385432;19966.600667986848;20382;20621.89689051545;11284.153688473561;11380.715482113013;11405.201635885409;11408.771824563628;11418;13930.930345348614;14239.359228834934;14250.358733856794;14291.28543572838;14304.642383940069;11320.773584528099;11402.714932126763;11634.36011019402;11731.706707332385;11953.940230298793;11269.66191014271;11421.31472111676;11467.655970320904;11582.6525204244;11970.880291196976;10185.388876667128;10420.06219440254;10423;10426.42654653971;10436.799768026774;7754.612269386395;7757.7672669819995;7765.339946104418;7784.571848548384;7862.056553213651;7536.359409450039;7591.013168288308;7613.961930190314;7695.23047695226;7748.728794492165 07:33:34 INFO - PID 30657 | |4;prototype.html;502.8013934495874;520.3287758791159;534.4601952028452;541.3314556522691;546.7457632201028;6133.110698948655;6189.504839612831;6221.7822376216845;6245.937540624604;6252.812415627527;6076.361981991893;6113.6943152842405;6119.418655227757;6120.081987701847;6180.938190618092;4925.556699897009;4931.309616653672;5021.623378246628;5049.873753156164;5085.23721441783;5032.345795046657;5063.341765570471;5065.4174769901565;5084.0594490019275;5136.409312929005;4959.181735013718;5030.974845125769;5042.924356134661;5059.139946209153;5066.695998240118;5014.949850501484;5017.9247311290355;5030.4215015272985;5042.974785126069;5049.621278404097;4565.680402371835;4581.862544123682;4584.862454126382;4609.723416595015;4616.469106052813;6163.784267550659;6203.534734894901;6208.751649933997;6221.87556248877;6229.37706229378;5656.886862262772;5700.6579605223405;5710.486056254916;5755.625884317506;5817.214676018768;6488.708008139645;6520.967395163041;6556.213254409454;6580.572262802951;6606.900896486556;4925.556699897026;4948.7278199699;4952.455229924705;4979.4771548987555;4996.850724333387;6892.620905850176;6903.827404314882;6918.861622767517;6930.965345173242;6987.580745155256;4954.9009019819405;5033.295338652519;5041.697498150123;5070.721110338966;5070.974645126825;5114.028334616411;5130.333056702605;5168.328117344721;5177.974110129425;5178.093833579109;5680.960686032952;5702.657840529581;5718.1708652246025;5728.283964504437;5779.133130030445;6230.971889638249;6437.420632143129;6543.509236807164;6569.244536878225;6614.9669251654395;1611.178299067484;1680.5462525118394;1723.6466524362475;1728.1186594836472;1734.37562477508;1222.767674141928;1223.7246619510718;1225.6200577820905;1251.8608066659297;1267.3473161321926;5568.303962004749;5601.719914004283;5619.915701264485;5683.261176047087;5691.943080569225;6657.533972621967;6678.398944094957;6707.6981535831;6733.192016957898;6755.088063111417;5538.0585300499015;5665;5681.431856814368;5685.857853553694;5689.630174038674;3533.169705119295;3558.928821423557;3568.8572457101427;3578.3022310649694;3592.7305452091196;6377.373769688793;6466.35336466359;6468.223813142453;6511.38141876521;6583.80248592543;6575.145231120019;6915.786948176536;6965.547239429421;6971.128608923885;6980.965095174492;6469.288378278292;6536.117624120683;6549.8690026200165;6555.967220163869;6571.57284776488;6654.301298363602;6726.630035348103;6747.359000894907;6840.452763778985;6873.175218973755;6695.698693558899;6709.698063587052;6717.664116794239;6740.157480314961;6752.265332540329;6467.450266727381;6511.153550038464;6513.967430163008;6517.217933847968;6533.41199292066;4992.400911890596;5001.174806156943;5045.672031317952;5049.848504544722;5077.31456253401;6431.935680643133;6576.736930522917;6714.0600315954825;6760.86478270422;6853.554518956252;6260.968695156495;6391.424771770563;6522.21733391996;6600.966995164993;6630.23752268476;6437.903431448625;6526.967365163144;6536.150300460909;6585.80242592723;6610.702518386588;6463.450606698295;6495.82389899389;6512.348765123639;6557.639329836905;6585.8717109372665;6304.842378940563;6529.183852018497;6614;6659.900101498384;6695.665216739046;6383.989043451298;6418.229812422539;6422.871542569029;6424.164858568356;6434.453071488976 07:33:34 INFO - PID 30657 | |5;yui.html;911.86777917202;916.6196028648111;919.6597259014162;922.7185708358952;932.4591736792659;19652.803471965275;19762.308319208812;20211.292604758837;20214.393568192958;20312;19196.136173872175;19203.423897283068;19340.323088691865;19406.90296548515;19514.31699890504;953.4565297780268;973.8101070291297;976.6874600127926;977.7848873247874;979.8628192053118;960;981.0336818234034;981.4013451794435;989.0949780950422;999.3054826895311;1061.3950048472375;1065.057424179599;1066.568039943824;1071.9464026798669;1072.1797824664138;991.2268430624125;1052.0899421999952;1052.18981384334;1054.5518154784263;1060.3478860500775;751.612919346537;752.9774106776805;758.298573819215;760.182803486249;760.2321655128305;20542.178312867618;20665;20674.379768606814;20763.377098687062;20778.376648700563;22011.669824952638;22084;22650.20724274659;22655.77344226553;22948.426289342733;17224.741628875578;17473.737893931473;17533.21100550478;17606.735898961524;17835.821641783547;3782.3191825471404;3800.9809950950344;3801.1067399161175;3801.5438147422487;3805.315043292206;140047;141944.290278549;142242;143403;145114;16571.50285491437;16633.417830375878;16646.6670666586;16713.749293760604;16768.748468772978;31941.52087718686;32084;32504.512432313535;32748;32801;8685.348598854986;8784.78038049054;8800.075992020873;8802.207801297785;8835.469871807583;3973.7019723520275;4144.41978123063;4186.769727665008;4191.790410479463;4198.265303571863;5482.259894914216;5587.69267690281;5594.552435805207;5619.26949496571;5629.971850140723;1027.9588816447258;1036.2538971940191;1052.0426411965227;1053.1627356251645;1055.778286559831;665.7869481765788;689.021589343124;693.272064332459;699.3915293694519;702.9437644988388;11140.888591113986;11298.661040168809;11559.537618495166;11746.88253117475;11803.350815705218;678.1929503890354;690.5304393012699;693.6739732325799;694.486080300584;710.5949608723082;5267.578593712494;5337.653052551572;5378.542823860016;5419;5474.616776825584;125301;126334.36832815777;126954.36522817326;127698.36150819187;128921.35539322243;107896;109498.45250773695;110023.44988275008;110661.44669276763;114019;94723;94951;96451.51774241084;97458;98601.50699246458;9935.20518358544;10003.499825008575;10258.846117308396;10287.382757034302;10350.068493835592;43121.78439107785;43335;43887.56112438835;44516.77741611271;44531;55073.44926550683;57055.71472142779;57101.428985711274;57222;57604;15578.909476336548;16132.435364762177;16141.59646008859;16186.109763963095;16282.918585406996;16157.27292271827;16539.83460165431;16621.916890415472;16786.748198777274;16832.242549085076;15077.698446030798;15170.848291516944;15281.6179595511;15505.689886201988;15563.922180389025;15040.924795375953;15121.773173402187;15199.772003419735;15247.3901043957;15415.306311215798;13651.658708532366;13837.238951857744;13917;14235.50175743844;14283.642908926944;11231;11372.20394572372;11545.48045337945;11662.650120496399;11833.349165795964;11418.143639227257;11476.368799715761;11536.480858361227;11628.592999244986;11692.883071169179 07:33:34 INFO - PID 30657 | __end_tp_report 07:33:34 INFO - PID 30657 | __start_cc_report 07:33:34 INFO - PID 30657 | _x_x_mozilla_cycle_collect,278 07:33:34 INFO - PID 30657 | __end_cc_report 07:33:34 INFO - PID 30657 | __startTimestamp1506004414654__endTimestamp 07:33:34 INFO - PID 30657 | 07:33:34 INFO - PID 30657 | ------- Summary: start ------- 07:33:34 INFO - PID 30657 | Number of tests: 6 07:33:34 INFO - PID 30657 | 07:33:34 INFO - PID 30657 | [#0] dojo.html Cycles:165 Average:6226.57 Median:6476.42 stddev:752.35 (11.6%) stddev-sans-first:714.05 07:33:34 INFO - PID 30657 | Values: 3118.0 3131.0 3146.6 3153.8 3174.2 6086.9 6260.5 6320.3 6390.6 6469.0 6255.7 6269.6 6285.5 6294.8 6316.1 5826.5 5827.9 5829.4 5836.9 5921.0 5878.7 5927.5 5932.3 5966.1 5980.4 5857.9 5878.9 5893.6 5968.4 5983.9 5674.0 5781.4 5786.4 5862.3 5883.8 5702.2 5793.4 5797.9 5858.8 5867.8 6162.0 6191.9 6206.7 6309.8 6349.3 6640.0 6732.5 6733.9 6797.0 6847.2 6813.3 6848.5 6859.8 6920.3 6920.3 6325.9 6352.7 6397.7 6405.0 6408.2 6910.1 6958.8 7013.5 7014.0 7040.8 6617.7 6633.2 6697.4 6734.7 6747.4 6837.4 6854.1 6855.9 6865.9 6868.2 6674.1 6736.5 6736.7 6827.2 6828.7 6540.9 6665.2 6703.6 6818.5 6840.8 5288.0 5316.1 5324.0 5356.8 5370.5 4657.0 4690.6 4692.9 4703.6 4733.1 5733.6 5751.8 5852.6 5858.4 5879.7 6617.9 6811.4 6846.4 6886.4 6957.1 5706.7 5723.1 5731.9 5746.9 5755.8 5700.6 5738.1 5745.3 5748.7 5780.9 6504.0 6517.1 6575.3 6638.9 6649.0 6853.1 6909.1 6922.4 6934.4 6939.4 6571.7 6582.5 6585.7 6670.2 6681.4 6555.0 6598.8 6605.4 6699.6 6828.7 6691.6 6731.3 6736.5 6753.2 6786.9 6476.4 6486.6 6487.6 6543.5 6557.1 6122.8 6369.5 6503.2 6511.5 6655.2 6337.5 6400.8 6484.2 6527.4 6531.3 6670.0 6695.2 6715.5 6738.5 6742.8 6277.4 6402.4 6431.5 6521.7 6535.1 07:33:34 INFO - PID 30657 | 07:33:34 INFO - PID 30657 | [#1] ext.html Cycles:180 Average:37275.17 Median:3502.42 stddev:53508.78 (1527.8%) stddev-sans-first:53589.07 07:33:34 INFO - PID 30657 | Values: 872.6 873.9 875.2 881.1 896.4 40146.6 41780.8 41867.2 41956.6 41999.0 37820.0 38032.8 38035.8 38050.2 38096.4 7221.4 7245.9 7266.2 7273.2 7277.2 7205.0 7219.5 7239.9 7269.5 7307.3 7306.5 7311.5 7313.0 7338.3 7415.5 7904.5 8073.7 8080.9 8085.3 8095.1 7919.9 7939.3 7957.7 7990.0 7993.6 38650.2 39253.0 39313.4 39340.0 39367.8 3460.7 3487.8 3487.9 3502.3 3502.6 31885.7 32083.5 32090.8 32094.4 32109.5 5244.8 5268.3 5287.3 5375.9 5395.4 190525.0 190845.0 191071.0 191410.0 191665.0 47273.8 47616.0 47701.3 47746.0 47797.5 49018.0 49469.8 49657.8 49839.0 49954.0 14072.8 14332.4 14354.1 14377.0 14537.4 11417.1 11418.4 11426.5 11460.1 11494.8 14219.9 14267.4 14343.2 14350.3 14506.8 5479.7 5525.9 5563.9 5644.9 5698.4 981.6 991.2 998.9 1015.4 1025.6 22577.0 22770.4 22802.9 22824.7 23005.8 1077.0 1095.4 1106.5 1107.0 1113.5 11938.2 12023.2 12137.3 12151.3 12250.2 228339.0 229721.0 229835.0 230337.0 230363.0 44440.6 45896.4 51596.5 51861.5 51887.7 1412.5 1414.1 1439.9 1449.3 1449.8 26715.9 27121.3 27364.0 27484.2 27486.5 37686.4 37836.0 38137.6 38166.0 38238.6 195800.0 195991.0 196700.0 197568.0 199212.0 32712.8 33765.0 33885.3 34259.0 34313.8 41217.7 41842.0 42286.4 42374.0 42454.4 31495.8 31830.5 32061.7 32403.5 32431.5 31123.0 31552.8 31807.0 31977.4 32221.8 35486.1 35666.6 35972.8 35989.3 36055.0 31518.4 31538.0 31614.8 31629.2 31749.0 22573.7 22599.7 23077.2 23148.7 23236.9 07:33:34 INFO - PID 30657 | 07:33:34 INFO - PID 30657 | [#2] jquery.html Cycles:180 Average:33997.96 Median:22792.93 stddev:80144.40 (351.6%) stddev-sans-first:80329.94 07:33:34 INFO - PID 30657 | Values: 574.2 574.4 579.7 600.5 606.2 21648.6 22786.7 22799.2 22803.4 22813.1 21774.2 22002.1 22019.9 22680.5 24705.4 6563.1 6758.1 6760.1 6762.8 6776.0 6611.7 6698.5 6725.2 6741.7 6766.9 6285.3 6622.4 6626.2 6626.5 6630.7 6491.2 6497.9 6531.5 6544.4 6547.5 5978.7 6030.1 6053.8 6234.7 6254.2 23308.7 23334.2 23494.5 23559.2 23591.8 1979.3 2014.0 2020.2 2021.4 2026.8 18301.7 19004.7 19159.2 19207.1 19222.2 2359.5 2377.8 2398.8 2416.7 2418.5 118436.4 122940.0 127706.7 131156.3 140079.0 14136.4 14322.9 14537.2 14549.0 14719.6 22842.9 23059.8 23105.9 23265.5 23272.8 8878.9 9346.6 9418.1 9444.4 9556.2 8340.9 8377.4 8380.5 8403.6 8407.0 9056.6 9174.7 9308.2 9325.2 9330.6 2634.0 2742.4 2748.3 2797.6 2802.0 947.4 961.3 970.6 976.3 982.4 18439.6 18476.8 18577.9 18875.7 18992.5 1006.9 1007.7 1016.6 1017.9 1022.4 10389.2 10396.3 10502.2 10536.0 10643.0 469250.0 471065.0 471777.0 479481.0 485460.0 107672.0 111282.0 111577.0 113102.4 113633.0 1084.5 1178.3 1204.2 1233.4 1248.1 19715.2 19949.8 20070.1 20151.5 20232.3 59180.7 61469.0 61710.0 61908.0 63089.4 80847.6 81373.0 81742.6 82324.2 83211.6 14181.9 14243.3 14405.9 14446.8 14466.9 21660.1 21743.8 21956.5 22232.1 22236.8 17683.8 17780.8 17814.1 17818.2 18123.5 14929.9 15888.4 16111.5 16767.2 16879.7 18031.7 18555.7 18600.7 18638.3 18683.5 14219.9 14228.1 14292.9 14295.3 14310.6 17229.3 17629.5 17744.8 17954.5 17977.5 07:33:34 INFO - PID 30657 | 07:33:34 INFO - PID 30657 | [#3] mootools.html Cycles:180 Average:9648.31 Median:2731.89 stddev:6851.82 (250.8%) stddev-sans-first:6842.48 07:33:34 INFO - PID 30657 | Values: 1321.6 1382.6 1400.1 1406.4 1406.6 14991.3 15700.4 15704.8 15721.0 15751.4 15354.8 15509.1 15515.1 15527.0 15531.8 2775.4 2829.7 2858.4 2867.0 2874.2 2719.3 2791.1 2799.6 2814.4 2817.3 2656.8 2811.9 2821.7 2921.8 2932.4 2858.2 2890.0 2894.7 2897.6 2901.1 2703.3 2731.9 2731.9 2739.7 2762.7 15238.2 15253.5 15462.8 15540.7 15546.9 8217.4 8531.8 8548.9 8570.6 8592.1 5264.7 5473.9 5629.9 5640.8 5677.0 1810.5 1859.0 1868.6 1912.5 1926.0 27426.9 27448.0 27653.6 27678.6 27791.3 7039.8 7053.5 7100.9 7259.6 7273.2 17959.5 18148.9 18219.1 18239.1 18274.3 8269.5 8370.2 8392.2 8462.0 8468.8 7214.7 7409.0 7457.9 7484.4 7488.5 5342.9 5564.8 5572.9 5582.7 5639.4 1610.0 1610.9 1623.0 1639.6 1647.8 757.0 902.2 921.8 931.3 941.8 8612.4 8926.9 8952.3 8989.2 9013.5 965.0 989.8 997.6 1008.8 1009.8 5106.3 5317.3 5341.8 5431.4 5523.0 21552.4 21950.7 22166.9 22331.2 22410.8 20752.3 21533.2 21852.1 21884.1 21972.8 11315.9 11486.0 11780.7 11798.1 11981.0 8169.1 8541.0 8631.3 8736.1 8763.0 18588.6 18755.7 18949.3 19319.8 19669.4 19098.1 19816.5 19966.6 20382.0 20621.9 11284.2 11380.7 11405.2 11408.8 11418.0 13930.9 14239.4 14250.4 14291.3 14304.6 11320.8 11402.7 11634.4 11731.7 11953.9 11269.7 11421.3 11467.7 11582.7 11970.9 10185.4 10420.1 10423.0 10426.4 10436.8 7754.6 7757.8 7765.3 7784.6 7862.1 7536.4 7591.0 7614.0 7695.2 7748.7 07:33:34 INFO - PID 30657 | 07:33:34 INFO - PID 30657 | [#4] prototype.html Cycles:180 Average:5526.88 Median:6172.36 stddev:1543.76 (25.0%) stddev-sans-first:1501.33 07:33:34 INFO - PID 30657 | Values: 502.8 520.3 534.5 541.3 546.7 6133.1 6189.5 6221.8 6245.9 6252.8 6076.4 6113.7 6119.4 6120.1 6180.9 4925.6 4931.3 5021.6 5049.9 5085.2 5032.3 5063.3 5065.4 5084.1 5136.4 4959.2 5031.0 5042.9 5059.1 5066.7 5014.9 5017.9 5030.4 5043.0 5049.6 4565.7 4581.9 4584.9 4609.7 4616.5 6163.8 6203.5 6208.8 6221.9 6229.4 5656.9 5700.7 5710.5 5755.6 5817.2 6488.7 6521.0 6556.2 6580.6 6606.9 4925.6 4948.7 4952.5 4979.5 4996.9 6892.6 6903.8 6918.9 6931.0 6987.6 4954.9 5033.3 5041.7 5070.7 5071.0 5114.0 5130.3 5168.3 5178.0 5178.1 5681.0 5702.7 5718.2 5728.3 5779.1 6231.0 6437.4 6543.5 6569.2 6615.0 1611.2 1680.5 1723.6 1728.1 1734.4 1222.8 1223.7 1225.6 1251.9 1267.3 5568.3 5601.7 5619.9 5683.3 5691.9 6657.5 6678.4 6707.7 6733.2 6755.1 5538.1 5665.0 5681.4 5685.9 5689.6 3533.2 3558.9 3568.9 3578.3 3592.7 6377.4 6466.4 6468.2 6511.4 6583.8 6575.1 6915.8 6965.5 6971.1 6981.0 6469.3 6536.1 6549.9 6556.0 6571.6 6654.3 6726.6 6747.4 6840.5 6873.2 6695.7 6709.7 6717.7 6740.2 6752.3 6467.5 6511.2 6514.0 6517.2 6533.4 4992.4 5001.2 5045.7 5049.8 5077.3 6431.9 6576.7 6714.1 6760.9 6853.6 6261.0 6391.4 6522.2 6601.0 6630.2 6437.9 6527.0 6536.2 6585.8 6610.7 6463.5 6495.8 6512.3 6557.6 6585.9 6304.8 6529.2 6614.0 6659.9 6695.7 6384.0 6418.2 6422.9 6424.2 6434.5 07:33:34 INFO - PID 30657 | 07:33:34 INFO - PID 30657 | [#5] yui.html Cycles:180 Average:24556.63 Median:20212.84 stddev:36088.82 (178.5%) stddev-sans-first:36146.39 07:33:34 INFO - PID 30657 | Values: 911.9 916.6 919.7 922.7 932.5 19652.8 19762.3 20211.3 20214.4 20312.0 19196.1 19203.4 19340.3 19406.9 19514.3 953.5 973.8 976.7 977.8 979.9 960.0 981.0 981.4 989.1 999.3 1061.4 1065.1 1066.6 1071.9 1072.2 991.2 1052.1 1052.2 1054.6 1060.3 751.6 753.0 758.3 760.2 760.2 20542.2 20665.0 20674.4 20763.4 20778.4 22011.7 22084.0 22650.2 22655.8 22948.4 17224.7 17473.7 17533.2 17606.7 17835.8 3782.3 3801.0 3801.1 3801.5 3805.3 140047.0 141944.3 142242.0 143403.0 145114.0 16571.5 16633.4 16646.7 16713.7 16768.7 31941.5 32084.0 32504.5 32748.0 32801.0 8685.3 8784.8 8800.1 8802.2 8835.5 3973.7 4144.4 4186.8 4191.8 4198.3 5482.3 5587.7 5594.6 5619.3 5630.0 1028.0 1036.3 1052.0 1053.2 1055.8 665.8 689.0 693.3 699.4 702.9 11140.9 11298.7 11559.5 11746.9 11803.4 678.2 690.5 693.7 694.5 710.6 5267.6 5337.7 5378.5 5419.0 5474.6 125301.0 126334.4 126954.4 127698.4 128921.4 107896.0 109498.5 110023.4 110661.4 114019.0 94723.0 94951.0 96451.5 97458.0 98601.5 9935.2 10003.5 10258.8 10287.4 10350.1 43121.8 43335.0 43887.6 44516.8 44531.0 55073.4 57055.7 57101.4 57222.0 57604.0 15578.9 16132.4 16141.6 16186.1 16282.9 16157.3 16539.8 16621.9 16786.7 16832.2 15077.7 15170.8 15281.6 15505.7 15563.9 15040.9 15121.8 15199.8 15247.4 15415.3 13651.7 13837.2 13917.0 14235.5 14283.6 11231.0 11372.2 11545.5 11662.7 11833.3 11418.1 11476.4 11536.5 11628.6 11692.9 07:33:34 INFO - PID 30657 | -------- Summary: end -------- 07:33:34 INFO - PID 30657 | 07:33:35 INFO - TEST-INFO | 30657: exit 0 07:33:35 INFO - TEST-OK | dromaeo_css | took 1113093ms 07:33:35 INFO - TEST-START | kraken 07:33:35 INFO - Initialising browser for kraken test... 07:33:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47440/getInfo.html -profile /tmp/tmpsV7sr1/profile 07:33:35 INFO - TEST-INFO | started process 31049 (/builds/slave/test/build/application/firefox/firefox http://localhost:47440/getInfo.html) 07:33:41 INFO - TEST-INFO | 31049: exit 0 07:33:41 INFO - Browser initialized. 07:33:41 INFO - Running cycle 1/1 for kraken test... 07:33:41 INFO - Using env: {'DISPLAY': ':0', 07:33:41 INFO - 'HOME': '/home/cltbld', 07:33:41 INFO - 'JSGC_DISABLE_POISONING': '1', 07:33:41 INFO - 'LANG': 'en_US.UTF-8', 07:33:41 INFO - 'LANGUAGE': 'en_US:en', 07:33:41 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:33:41 INFO - 'LOGNAME': 'cltbld', 07:33:41 INFO - 'MAIL': '/var/mail/cltbld', 07:33:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:33:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:33:41 INFO - 'MOZ_ACCELERATED': '1', 07:33:41 INFO - 'MOZ_CRASHREPORTER': '1', 07:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:41 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:33:41 INFO - 'MOZ_NO_REMOTE': '1', 07:33:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:33:41 INFO - 'MOZ_WEBRENDER': '1', 07:33:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:41 INFO - 'NO_EM_RESTART': '1', 07:33:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:41 INFO - 'PWD': '/builds/slave/test', 07:33:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:33:41 INFO - 'RUST_BACKTRACE': 'full', 07:33:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:33:41 INFO - 'SHELL': '/bin/bash', 07:33:41 INFO - 'SHLVL': '1', 07:33:41 INFO - 'TERM': 'linux', 07:33:41 INFO - 'TMOUT': '86400', 07:33:41 INFO - 'USER': 'cltbld', 07:33:41 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1506002765.801343-1765993885', 07:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:41 INFO - '_': '/tools/buildbot/bin/python'} 07:33:41 INFO - TEST-INFO | started process 31295 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsV7sr1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:42 INFO - PID 31295 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 07:33:42 INFO - PID 31295 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 07:33:43 INFO - PID 31295 | 07:33:43 INFO - PID 31295 | (/builds/slave/test/build/application/firefox/firefox:31354): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:33:43 INFO - PID 31295 | 07:33:53 INFO - PID 31295 | RSS: Main: 208019456 07:33:53 INFO - PID 31295 | 07:33:56 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47440/tests/kraken/driver.html?testName=audio-beat-detection) 07:33:56 INFO - PID 31295 | RSS: Main: 209465344 07:33:56 INFO - PID 31295 | 07:33:59 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47440/tests/kraken/driver.html?testName=audio-dft) 07:34:00 INFO - PID 31295 | RSS: Main: 209301504 07:34:00 INFO - PID 31295 | 07:34:03 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47440/tests/kraken/driver.html?testName=audio-fft) 07:34:03 INFO - PID 31295 | RSS: Main: 206118912 07:34:03 INFO - PID 31295 | 07:34:06 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47440/tests/kraken/driver.html?testName=audio-oscillator) 07:34:06 INFO - PID 31295 | RSS: Main: 207429632 07:34:06 INFO - PID 31295 | 07:34:08 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47440/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:34:09 INFO - PID 31295 | RSS: Main: 207908864 07:34:09 INFO - PID 31295 | 07:34:12 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47440/tests/kraken/driver.html?testName=imaging-darkroom) 07:34:13 INFO - PID 31295 | RSS: Main: 209879040 07:34:13 INFO - PID 31295 | 07:34:17 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47440/tests/kraken/driver.html?testName=imaging-desaturate) 07:34:17 INFO - PID 31295 | RSS: Main: 208257024 07:34:17 INFO - PID 31295 | 07:34:20 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47440/tests/kraken/driver.html?testName=json-parse-financial) 07:34:21 INFO - PID 31295 | RSS: Main: 209256448 07:34:21 INFO - PID 31295 | 07:34:23 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47440/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:34:23 INFO - PID 31295 | RSS: Main: 209977344 07:34:23 INFO - PID 31295 | 07:34:25 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:34:26 INFO - PID 31295 | RSS: Main: 207134720 07:34:26 INFO - PID 31295 | 07:34:28 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:34:28 INFO - PID 31295 | RSS: Main: 207028224 07:34:28 INFO - PID 31295 | 07:34:31 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:34:32 INFO - PID 31295 | RSS: Main: 207585280 07:34:32 INFO - PID 31295 | 07:34:35 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:34:35 INFO - PID 31295 | RSS: Main: 207204352 07:34:35 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | Cycle 1(1): loaded http://localhost:47440/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47440/tests/kraken/driver.html?testName=ai-astar) 07:34:37 INFO - PID 31295 | RSS: Main: 209022976 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | __start_tp_report 07:34:37 INFO - PID 31295 | _x_x_mozilla_page_load 07:34:37 INFO - PID 31295 | _x_x_mozilla_page_load_details 07:34:37 INFO - PID 31295 | |i|pagename|runs| 07:34:37 INFO - PID 31295 | |0;ai-astar;116;111;107;108;109;110;110;106;107;108 07:34:37 INFO - PID 31295 | |1;audio-beat-detection;126;120;116;120;118;126;119;122;148;121 07:34:37 INFO - PID 31295 | |2;audio-dft;163;168;169;175;169;169;178;163;163;169 07:34:37 INFO - PID 31295 | |3;audio-fft;83;76;82;76;82;86;81;80;76;74 07:34:37 INFO - PID 31295 | |4;audio-oscillator;87;89;79;78;87;82;82;88;85;81 07:34:37 INFO - PID 31295 | |5;imaging-gaussian-blur;115;119;118;116;118;115;116;118;115;117 07:34:37 INFO - PID 31295 | |6;imaging-darkroom;150;154;153;154;151;148;150;152;148;153 07:34:37 INFO - PID 31295 | |7;imaging-desaturate;94;94;96;97;94;101;94;95;105;100 07:34:37 INFO - PID 31295 | |8;json-parse-financial;54;52;62;53;54;54;57;58;52;53 07:34:37 INFO - PID 31295 | |9;json-stringify-tinderbox;45;49;39;46;44;38;40;38;39;38 07:34:37 INFO - PID 31295 | |10;stanford-crypto-aes;78;77;87;76;80;78;79;71;72;74 07:34:37 INFO - PID 31295 | |11;stanford-crypto-ccm;116;116;112;115;121;120;112;122;118;120 07:34:37 INFO - PID 31295 | |12;stanford-crypto-pbkdf2;173;170;181;170;168;176;178;170;183;176 07:34:37 INFO - PID 31295 | |13;stanford-crypto-sha256-iterative;66;70;65;74;67;69;67;65;67;70 07:34:37 INFO - PID 31295 | __end_tp_report 07:34:37 INFO - PID 31295 | __start_cc_report 07:34:37 INFO - PID 31295 | _x_x_mozilla_cycle_collect,728 07:34:37 INFO - PID 31295 | __end_cc_report 07:34:37 INFO - PID 31295 | __startTimestamp1506004477845__endTimestamp 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | ------- Summary: start ------- 07:34:37 INFO - PID 31295 | Number of tests: 14 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#0] ai-astar Cycles:10 Average:109.20 Median:109.50 stddev:2.86 (2.6%) stddev-sans-first:1.67 07:34:37 INFO - PID 31295 | Values: 116.0 111.0 107.0 108.0 109.0 110.0 110.0 106.0 107.0 108.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:121.50 stddev:9.14 (7.5%) stddev-sans-first:9.66 07:34:37 INFO - PID 31295 | Values: 126.0 120.0 116.0 120.0 118.0 126.0 119.0 122.0 148.0 121.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#2] audio-dft Cycles:10 Average:168.60 Median:169.00 stddev:4.99 (3.0%) stddev-sans-first:4.87 07:34:37 INFO - PID 31295 | Values: 163.0 168.0 169.0 175.0 169.0 169.0 178.0 163.0 163.0 169.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#3] audio-fft Cycles:10 Average:79.60 Median:81.50 stddev:3.89 (4.8%) stddev-sans-first:3.93 07:34:37 INFO - PID 31295 | Values: 83.0 76.0 82.0 76.0 82.0 86.0 81.0 80.0 76.0 74.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#4] audio-oscillator Cycles:10 Average:83.80 Median:86.00 stddev:3.91 (4.5%) stddev-sans-first:3.97 07:34:37 INFO - PID 31295 | Values: 87.0 89.0 79.0 78.0 87.0 82.0 82.0 88.0 85.0 81.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.50 stddev:1.49 (1.3%) stddev-sans-first:1.45 07:34:37 INFO - PID 31295 | Values: 115.0 119.0 118.0 116.0 118.0 115.0 116.0 118.0 115.0 117.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:152.50 stddev:2.26 (1.5%) stddev-sans-first:2.35 07:34:37 INFO - PID 31295 | Values: 150.0 154.0 153.0 154.0 151.0 148.0 150.0 152.0 148.0 153.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#7] imaging-desaturate Cycles:10 Average:97.00 Median:94.00 stddev:3.80 (4.0%) stddev-sans-first:3.87 07:34:37 INFO - PID 31295 | Values: 94.0 94.0 96.0 97.0 94.0 101.0 94.0 95.0 105.0 100.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:54.00 stddev:3.18 (5.9%) stddev-sans-first:3.35 07:34:37 INFO - PID 31295 | Values: 54.0 52.0 62.0 53.0 54.0 54.0 57.0 58.0 52.0 53.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#9] json-stringify-tinderbox Cycles:10 Average:41.60 Median:42.00 stddev:4.03 (9.6%) stddev-sans-first:4.09 07:34:37 INFO - PID 31295 | Values: 45.0 49.0 39.0 46.0 44.0 38.0 40.0 38.0 39.0 38.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#10] stanford-crypto-aes Cycles:10 Average:77.20 Median:78.00 stddev:4.54 (5.8%) stddev-sans-first:4.81 07:34:37 INFO - PID 31295 | Values: 78.0 77.0 87.0 76.0 80.0 78.0 79.0 71.0 72.0 74.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#11] stanford-crypto-ccm Cycles:10 Average:117.20 Median:119.00 stddev:3.58 (3.0%) stddev-sans-first:3.77 07:34:37 INFO - PID 31295 | Values: 116.0 116.0 112.0 115.0 121.0 120.0 112.0 122.0 118.0 120.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:176.00 stddev:5.13 (2.9%) stddev-sans-first:5.41 07:34:37 INFO - PID 31295 | Values: 173.0 170.0 181.0 170.0 168.0 176.0 178.0 170.0 183.0 176.0 07:34:37 INFO - PID 31295 | 07:34:37 INFO - PID 31295 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:2.79 (4.1%) stddev-sans-first:2.86 07:34:37 INFO - PID 31295 | Values: 66.0 70.0 65.0 74.0 67.0 69.0 67.0 65.0 67.0 70.0 07:34:37 INFO - PID 31295 | -------- Summary: end -------- 07:34:37 INFO - PID 31295 | 07:34:38 INFO - PID 31295 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 07:34:38 INFO - TEST-INFO | 31295: exit 0 07:34:38 INFO - TEST-OK | kraken | took 63084ms 07:34:38 INFO - SUITE-END | took 1176s 07:34:38 INFO - Completed test suite (00:19:36) 07:34:38 INFO - javascript benchmark 07:34:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8528.650320371424, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3118.0022392834294, 3131.0, 3146.5752123463326, 3153.7792354535177, 3174.2381828361185, 6086.878262434749, 6260.525012382367, 6320.273168585608, 6390.5846120002225, 6468.967655161718, 6255.718492667829, 6269.592476489025, 6285.497160227183, 6294.8111556653375, 6316.147320111783, 5826.504747096491, 5827.854303642401, 5829.387914269004, 5836.941630583682, 5921.023031199847, 5878.706064696769, 5927.525797936155, 5932.288125424955, 5966.075258334943, 5980.372060933604, 5857.85355366115, 5878.853028674296, 5893.557983151238, 5968.4031596840405, 5983.850403739898, 5673.971630141864, 5781.364049954502, 5786.363500014995, 5862.267216597926, 5883.794067207627, 5702.201691763156, 5793.449622285876, 5797.942020579824, 5858.794942177046, 5867.823965281048, 6161.9691901540655, 6191.907121393183, 6206.6586337751405, 6309.779157729503, 6349.269833969107, 6640.0, 6732.4950628703045, 6733.865322693568, 6797.01443290725, 6847.1783385994, 6813.284605116441, 6848.520603557753, 6859.759908403182, 6920.30796920309, 6920.342567456134, 6325.936740632626, 6352.682365881734, 6397.6801159941815, 6404.967975160094, 6408.166938298038, 6910.06714093601, 6958.79123626292, 7013.544119632207, 7014.018037474758, 7040.823979400454, 6617.735290588419, 6633.170853643294, 6697.397234248941, 6734.696938637773, 6747.392734653807, 6837.38463538274, 6854.143232095988, 6855.897161542581, 6865.897011544831, 6868.210155832044, 6674.06563081169, 6736.528443008942, 6736.730530778812, 6827.249002609709, 6828.726850926007, 6540.869182616321, 6665.166854143232, 6703.631300278434, 6818.522703410813, 6840.828979275459, 5287.9735601321745, 5316.149416093406, 5324.041672498987, 5356.7857285708915, 5370.516653501126, 4657.0, 4690.554397332248, 4692.859214223578, 4703.576678098919, 4733.077049975222, 5733.627314224562, 5751.827445176651, 5852.648841069549, 5858.355580886099, 5879.735411906475, 6617.900731488935, 6811.386975172259, 6846.383825455733, 6886.449084073262, 6957.060796792368, 5706.657600543981, 5723.055695810144, 5731.885362292731, 5746.88506229873, 5755.820056888328, 5700.572457065737, 5738.08190689496, 5745.339285982082, 5748.655080695172, 5780.88438231233, 6504.0, 6517.055027021055, 6575.342465753482, 6638.86722265552, 6648.966755166193, 6853.109095817512, 6909.067275917787, 6922.376986071277, 6934.4452443805385, 6939.375456208864, 6571.671416429255, 6582.473402127914, 6585.670716464062, 6670.232923213698, 6681.365270299314, 6554.967225163844, 6598.802035938929, 6605.372489613479, 6699.598024118373, 6828.692708828214, 6691.5650482718465, 6731.259561448334, 6736.461083113437, 6753.223379311442, 6786.898196526958, 6476.417122459001, 6486.610803351814, 6487.610743355413, 6543.476521878333, 6557.147570815763, 6122.846928826637, 6369.522285828674, 6503.219613646393, 6511.511636627177, 6655.23464801554, 6337.493000560036, 6400.807975760735, 6484.157059582413, 6527.412532872065, 6531.3468653134305, 6669.966650166913, 6695.196576410862, 6715.496337774589, 6738.460923126237, 6742.764003259862, 6277.435030847246, 6402.391772781578, 6431.517636177399, 6521.739130434729, 6535.052417399545], "value": 6168.743771798501, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [872.6334939325483, 873.8601823708206, 875.1642181716461, 881.1232823340773, 896.4397251717677, 40146.59853401465, 41780.79109604455, 41867.16265674685, 41956.58043419569, 41999.0, 37820.0, 38032.80983595078, 38035.809820950926, 38050.238995220076, 38096.42855357172, 7221.350078492935, 7245.8913116303165, 7266.200717921024, 7273.163586187603, 7277.19950805411, 7205.0, 7219.458540609476, 7239.927600723978, 7269.527480713763, 7307.269273072703, 7306.452016098788, 7311.4516411269105, 7313.0, 7338.266173382673, 7415.480916335859, 7904.4862083964645, 8073.677052917877, 8080.878786818203, 8085.272325490734, 8095.069067057246, 7919.881201781978, 7939.28546430824, 7957.681692732283, 7989.960050199712, 7993.600319983978, 38650.22699545993, 39253.0, 39313.41029884554, 39340.0, 39367.818965431085, 3460.653934606544, 3487.8430470628628, 3487.860485580574, 3502.2820321833965, 3502.562179727534, 31885.681143188736, 32083.51874721881, 32090.839545802355, 32094.358112837846, 32109.51835722466, 5244.763985620618, 5268.341457317835, 5287.312649355559, 5375.892482150335, 5395.433479484637, 190525.0, 190845.0, 191071.0, 191410.0, 191665.04167479213, 47273.763631181624, 47616.0, 47701.284480732815, 47746.0, 47797.52202478, 49018.0, 49469.752651237235, 49657.75171124194, 49839.0, 49954.0, 14072.788908166385, 14332.426702931974, 14354.138751674933, 14377.0, 14537.418503259963, 11417.086633069335, 11418.371989540488, 11426.485808138654, 11460.083193344512, 11494.827577586342, 14219.928900355431, 14267.42930282777, 14343.211123388313, 14350.282485875665, 14506.782398264035, 5479.671219726829, 5525.889482210333, 5563.888722225533, 5644.8871022579315, 5698.401667824902, 981.6269817469316, 991.1822746234385, 998.8801343838641, 1015.4465816130227, 1025.646152077532, 22577.0, 22770.43073923132, 22802.885985569967, 22824.65763013556, 23005.769942300696, 1076.962306319275, 1095.4413249242943, 1106.4578356605205, 1106.9594581093745, 1113.493360520961, 11938.164328497096, 12023.218490798245, 12137.271763694207, 12151.254211905652, 12250.221109595472, 228339.0, 229721.0, 229835.0, 230337.0, 230363.0, 44440.55559444429, 45896.39362622359, 51596.48403515992, 51861.48138518642, 51887.74056129695, 1412.5479846449039, 1414.073781673006, 1439.8776104031065, 1449.3115770009372, 1449.8187726534184, 26715.86642066855, 27121.321966950985, 27364.0, 27484.175474735788, 27486.450270994068, 37686.434703480016, 37836.0, 38137.6186238134, 38166.0, 38238.617613824616, 195800.0, 195991.0, 196700.0, 197568.0, 199212.0, 32712.83643581767, 33765.0, 33885.3222935535, 34259.0, 34313.828430857684, 41217.73302468273, 41842.0, 42286.365704515076, 42374.0, 42454.363184551636, 31495.84252078725, 31830.522542161423, 32061.67938320587, 32403.51394729128, 32431.513527297582, 31123.0, 31552.842235788674, 31807.04578862638, 31977.360452790348, 32221.838890805397, 35486.11284717903, 35666.64333356737, 35972.82013589915, 35989.28021439609, 36055.0, 31518.36963260768, 31538.0, 31614.841925790224, 31629.209269767518, 31749.04752857418, 22573.661395078758, 22599.661005084607, 23077.19229826948, 23148.652770208122, 23236.883815580815], "value": 16953.247188150093, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.1760573576918, 574.4227051812927, 579.7246308003698, 600.5015836855409, 606.2391698418483, 21648.567028659418, 22786.65820012701, 22799.202027929026, 22803.429914252152, 22813.08747650094, 21774.237901673445, 22002.119915203373, 22019.889900550515, 22680.54638907221, 24705.382365440873, 6563.081168636383, 6758.1214442122455, 6760.121184246041, 6762.8309292267595, 6775.9661201693925, 6611.702473388685, 6698.497612679044, 6725.226598941135, 6741.696623651923, 6766.898496522556, 6285.308616052231, 6622.370874766913, 6626.237982632011, 6626.469882409419, 6630.73477060917, 6491.22105347359, 6497.902531462032, 6531.542792004561, 6544.443722283611, 6547.541672082956, 5978.701064946736, 6030.09548567714, 6053.818385448443, 6234.688265586702, 6254.249490061222, 23308.65037024446, 23334.18330358446, 23494.53010939789, 23559.175428859908, 23591.76408235913, 1979.3468155508647, 2013.9798602013939, 2020.202020202012, 2021.4036859126593, 2026.7973202679761, 18301.7254741179, 19004.714929276073, 19159.23363065476, 19207.135678894345, 19222.231110755554, 2359.4809141988735, 2377.8229776260687, 2398.760123987605, 2416.7099948006353, 2418.516296740659, 118436.40781796123, 122940.0, 127706.72293277041, 131156.34421827926, 140079.0, 14136.434542618386, 14322.928385358007, 14537.200453974921, 14549.0, 14719.55841324762, 22842.885785570965, 23059.769402305763, 23105.884470577876, 23265.534689306118, 23272.767272327397, 8878.911210887938, 9346.626134954662, 9418.10527999839, 9444.433333999981, 9556.187724043533, 8340.916590834015, 8377.37169712274, 8380.497170169809, 8403.579821009047, 8407.0, 9056.592453339616, 9174.724758257264, 9308.20880225175, 9325.160735533835, 9330.626774929062, 2633.9868300658372, 2742.3555464465835, 2748.2854457841104, 2797.5943488194507, 2801.957970630442, 947.4126041853957, 961.2502248246379, 970.5632465390603, 976.3312131190157, 982.3614650477247, 18439.631207376045, 18476.815231847777, 18577.88532688016, 18875.716864247046, 18992.525186870163, 1006.9144122749502, 1007.652359935821, 1016.6187679620142, 1017.9134773544332, 1022.4070039377143, 10389.168866490663, 10396.324238924446, 10502.212334074975, 10536.0, 10643.0, 469250.0, 471065.0, 471777.0, 479481.0, 485460.0, 107672.0, 111282.0, 111577.0, 113102.43448782703, 113633.0, 1084.5499117866154, 1178.3283528388736, 1204.1871736577775, 1233.4018001269378, 1248.095131029996, 19715.211391544177, 19949.800501994796, 20070.09684564169, 20151.496212594804, 20232.291869784487, 59180.70409647924, 61469.0, 61710.0, 61908.0, 63089.36910631019, 80847.5957620208, 81373.0, 81742.59128704318, 82324.17675823165, 83211.58394208232, 14181.858181418054, 14243.287835607971, 14405.919556033548, 14446.783298250324, 14466.927665361605, 21660.133594656036, 21743.782562174176, 21956.451088722908, 22232.110715571194, 22236.77763222347, 17683.823161768218, 17780.822191777916, 17814.109294534963, 17818.19818108162, 18123.456296310604, 14929.925350373178, 15888.443904463751, 16111.516654500383, 16767.24547395346, 16879.662406752042, 18031.729524057413, 18555.72166417532, 18600.720989185444, 18638.34765783191, 18683.53291167677, 14219.928900355431, 14228.14631122136, 14292.857071429153, 14295.285235737963, 14310.57068287953, 17229.31082756676, 17629.471115866545, 17744.822551774832, 17954.461366159037, 17977.460676179737], "value": 11752.505312151856, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.550672771258, 1382.578313614348, 1400.1179257068045, 1406.3952500424814, 1406.5639651707977, 14991.325390357446, 15700.371985120595, 15704.764428533581, 15721.0, 15751.369945202177, 15354.769678454804, 15509.146996915166, 15515.146666933342, 15527.0, 15531.84468155318, 2775.3616668166333, 2829.740765359416, 2858.35686970431, 2866.9569956450564, 2874.209592362107, 2719.3473566344132, 2791.148699646607, 2799.6360473138566, 2814.3808362160294, 2817.2534278416238, 2656.8007399445023, 2811.8734656940487, 2821.6896141424427, 2921.839298838563, 2932.428176505582, 2858.213991152429, 2890.0318393338257, 2894.72500112489, 2897.6088228089147, 2901.0571564241704, 2703.3106557827628, 2731.877065532056, 2731.9043833465735, 2739.7260273972643, 2762.6822915364787, 15238.161901095435, 15253.466128685553, 15462.845371546253, 15540.689186216212, 15546.9222653886, 8217.383696222747, 8531.786705332353, 8548.914510854875, 8570.614322355508, 8592.14078592142, 5264.710440925785, 5473.945260547383, 5629.887402251973, 5640.774369025234, 5676.971615141939, 1810.4840120565575, 1858.9628207435776, 1868.6449574580786, 1912.531429799694, 1925.9614807703908, 27426.862865685544, 27448.0, 27653.585196222073, 27678.584821227698, 27791.305217369525, 7039.753608623776, 7053.506254562234, 7100.857982840314, 7259.564426134343, 7273.16358618755, 17959.461216163538, 18148.909255453902, 18219.08904554767, 18239.088045597666, 18274.269029238683, 8269.503829770232, 8370.246677799027, 8392.160783921681, 8462.0, 8468.830623387497, 7214.67533960973, 7409.036825212688, 7457.888131678029, 7484.363829074481, 7488.475806693588, 5342.91985620216, 5564.777408903598, 5572.881490604286, 5582.692951887685, 5639.407862174413, 1609.9597510062108, 1610.919454027294, 1622.9837701622832, 1639.5819066138058, 1647.8352164783425, 757.0, 902.1925376787739, 921.8386782313069, 931.2503434735104, 941.8493041113389, 8612.397132200686, 8926.910730892609, 8952.283817294601, 8989.190972812348, 9013.504257265913, 964.9855252171224, 989.8168838764851, 997.581015973293, 1008.7881544875659, 1009.7929924365487, 5106.336176297058, 5317.30874986257, 5341.81303654378, 5431.402545719968, 5523.033469142884, 21552.353429397142, 21950.670739938912, 22166.88916555407, 22331.21840735599, 22410.775892241196, 20752.273670421462, 21533.24633637815, 21852.12591496354, 21884.12463501442, 21972.780272197073, 11315.886841131647, 11485.966263036367, 11780.705482362839, 11798.056155507538, 11981.041516678644, 8169.142240064708, 8540.957295213733, 8631.26634236097, 8736.08271131522, 8763.036066032859, 18588.628227435645, 18755.718664219774, 18949.33677321287, 19319.806801932362, 19669.40991770249, 19098.140583674045, 19816.504587385432, 19966.600667986848, 20382.0, 20621.89689051545, 11284.153688473561, 11380.715482113013, 11405.201635885409, 11408.771824563628, 11418.0, 13930.930345348614, 14239.359228834934, 14250.358733856794, 14291.28543572838, 14304.642383940069, 11320.773584528099, 11402.714932126763, 11634.36011019402, 11731.706707332385, 11953.940230298793, 11269.66191014271, 11421.31472111676, 11467.655970320904, 11582.6525204244, 11970.880291196976, 10185.388876667128, 10420.06219440254, 10423.0, 10426.42654653971, 10436.799768026774, 7754.612269386395, 7757.7672669819995, 7765.339946104418, 7784.571848548384, 7862.056553213651, 7536.359409450039, 7591.013168288308, 7613.961930190314, 7695.23047695226, 7748.728794492165], "value": 6908.5314326531125, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.8013934495874, 520.3287758791159, 534.4601952028452, 541.3314556522691, 546.7457632201028, 6133.110698948655, 6189.504839612831, 6221.7822376216845, 6245.937540624604, 6252.812415627527, 6076.361981991893, 6113.6943152842405, 6119.418655227757, 6120.081987701847, 6180.938190618092, 4925.556699897009, 4931.309616653672, 5021.623378246628, 5049.873753156164, 5085.23721441783, 5032.345795046657, 5063.341765570471, 5065.4174769901565, 5084.0594490019275, 5136.409312929005, 4959.181735013718, 5030.974845125769, 5042.924356134661, 5059.139946209153, 5066.695998240118, 5014.949850501484, 5017.9247311290355, 5030.4215015272985, 5042.974785126069, 5049.621278404097, 4565.680402371835, 4581.862544123682, 4584.862454126382, 4609.723416595015, 4616.469106052813, 6163.784267550659, 6203.534734894901, 6208.751649933997, 6221.87556248877, 6229.37706229378, 5656.886862262772, 5700.6579605223405, 5710.486056254916, 5755.625884317506, 5817.214676018768, 6488.708008139645, 6520.967395163041, 6556.213254409454, 6580.572262802951, 6606.900896486556, 4925.556699897026, 4948.7278199699, 4952.455229924705, 4979.4771548987555, 4996.850724333387, 6892.620905850176, 6903.827404314882, 6918.861622767517, 6930.965345173242, 6987.580745155256, 4954.9009019819405, 5033.295338652519, 5041.697498150123, 5070.721110338966, 5070.974645126825, 5114.028334616411, 5130.333056702605, 5168.328117344721, 5177.974110129425, 5178.093833579109, 5680.960686032952, 5702.657840529581, 5718.1708652246025, 5728.283964504437, 5779.133130030445, 6230.971889638249, 6437.420632143129, 6543.509236807164, 6569.244536878225, 6614.9669251654395, 1611.178299067484, 1680.5462525118394, 1723.6466524362475, 1728.1186594836472, 1734.37562477508, 1222.767674141928, 1223.7246619510718, 1225.6200577820905, 1251.8608066659297, 1267.3473161321926, 5568.303962004749, 5601.719914004283, 5619.915701264485, 5683.261176047087, 5691.943080569225, 6657.533972621967, 6678.398944094957, 6707.6981535831, 6733.192016957898, 6755.088063111417, 5538.0585300499015, 5665.0, 5681.431856814368, 5685.857853553694, 5689.630174038674, 3533.169705119295, 3558.928821423557, 3568.8572457101427, 3578.3022310649694, 3592.7305452091196, 6377.373769688793, 6466.35336466359, 6468.223813142453, 6511.38141876521, 6583.80248592543, 6575.145231120019, 6915.786948176536, 6965.547239429421, 6971.128608923885, 6980.965095174492, 6469.288378278292, 6536.117624120683, 6549.8690026200165, 6555.967220163869, 6571.57284776488, 6654.301298363602, 6726.630035348103, 6747.359000894907, 6840.452763778985, 6873.175218973755, 6695.698693558899, 6709.698063587052, 6717.664116794239, 6740.157480314961, 6752.265332540329, 6467.450266727381, 6511.153550038464, 6513.967430163008, 6517.217933847968, 6533.41199292066, 4992.400911890596, 5001.174806156943, 5045.672031317952, 5049.848504544722, 5077.31456253401, 6431.935680643133, 6576.736930522917, 6714.0600315954825, 6760.86478270422, 6853.554518956252, 6260.968695156495, 6391.424771770563, 6522.21733391996, 6600.966995164993, 6630.23752268476, 6437.903431448625, 6526.967365163144, 6536.150300460909, 6585.80242592723, 6610.702518386588, 6463.450606698295, 6495.82389899389, 6512.348765123639, 6557.639329836905, 6585.8717109372665, 6304.842378940563, 6529.183852018497, 6614.0, 6659.900101498384, 6695.665216739046, 6383.989043451298, 6418.229812422539, 6422.871542569029, 6424.164858568356, 6434.453071488976], "value": 5073.943893643537, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [911.86777917202, 916.6196028648111, 919.6597259014162, 922.7185708358952, 932.4591736792659, 19652.803471965275, 19762.308319208812, 20211.292604758837, 20214.393568192958, 20312.0, 19196.136173872175, 19203.423897283068, 19340.323088691865, 19406.90296548515, 19514.31699890504, 953.4565297780268, 973.8101070291297, 976.6874600127926, 977.7848873247874, 979.8628192053118, 960.0, 981.0336818234034, 981.4013451794435, 989.0949780950422, 999.3054826895311, 1061.3950048472375, 1065.057424179599, 1066.568039943824, 1071.9464026798669, 1072.1797824664138, 991.2268430624125, 1052.0899421999952, 1052.18981384334, 1054.5518154784263, 1060.3478860500775, 751.612919346537, 752.9774106776805, 758.298573819215, 760.182803486249, 760.2321655128305, 20542.178312867618, 20665.0, 20674.379768606814, 20763.377098687062, 20778.376648700563, 22011.669824952638, 22084.0, 22650.20724274659, 22655.77344226553, 22948.426289342733, 17224.741628875578, 17473.737893931473, 17533.21100550478, 17606.735898961524, 17835.821641783547, 3782.3191825471404, 3800.9809950950344, 3801.1067399161175, 3801.5438147422487, 3805.315043292206, 140047.0, 141944.290278549, 142242.0, 143403.0, 145114.0, 16571.50285491437, 16633.417830375878, 16646.6670666586, 16713.749293760604, 16768.748468772978, 31941.52087718686, 32084.0, 32504.512432313535, 32748.0, 32801.0, 8685.348598854986, 8784.78038049054, 8800.075992020873, 8802.207801297785, 8835.469871807583, 3973.7019723520275, 4144.41978123063, 4186.769727665008, 4191.790410479463, 4198.265303571863, 5482.259894914216, 5587.69267690281, 5594.552435805207, 5619.26949496571, 5629.971850140723, 1027.9588816447258, 1036.2538971940191, 1052.0426411965227, 1053.1627356251645, 1055.778286559831, 665.7869481765788, 689.021589343124, 693.272064332459, 699.3915293694519, 702.9437644988388, 11140.888591113986, 11298.661040168809, 11559.537618495166, 11746.88253117475, 11803.350815705218, 678.1929503890354, 690.5304393012699, 693.6739732325799, 694.486080300584, 710.5949608723082, 5267.578593712494, 5337.653052551572, 5378.542823860016, 5419.0, 5474.616776825584, 125301.0, 126334.36832815777, 126954.36522817326, 127698.36150819187, 128921.35539322243, 107896.0, 109498.45250773695, 110023.44988275008, 110661.44669276763, 114019.0, 94723.0, 94951.0, 96451.51774241084, 97458.0, 98601.50699246458, 9935.20518358544, 10003.499825008575, 10258.846117308396, 10287.382757034302, 10350.068493835592, 43121.78439107785, 43335.0, 43887.56112438835, 44516.77741611271, 44531.0, 55073.44926550683, 57055.71472142779, 57101.428985711274, 57222.0, 57604.0, 15578.909476336548, 16132.435364762177, 16141.59646008859, 16186.109763963095, 16282.918585406996, 16157.27292271827, 16539.83460165431, 16621.916890415472, 16786.748198777274, 16832.242549085076, 15077.698446030798, 15170.848291516944, 15281.6179595511, 15505.689886201988, 15563.922180389025, 15040.924795375953, 15121.773173402187, 15199.772003419735, 15247.3901043957, 15415.306311215798, 13651.658708532366, 13837.238951857744, 13917.0, 14235.50175743844, 14283.642908926944, 11231.0, 11372.20394572372, 11545.48045337945, 11662.650120496399, 11833.349165795964, 11418.143639227257, 11476.368799715761, 11536.480858361227, 11628.592999244986, 11692.883071169179], "value": 8931.941187881988, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1463.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 111.0, 107.0, 108.0, 109.0, 110.0, 110.0, 106.0, 107.0, 108.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 120.0, 116.0, 120.0, 118.0, 126.0, 119.0, 122.0, 148.0, 121.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 168.0, 169.0, 175.0, 169.0, 169.0, 178.0, 163.0, 163.0, 169.0], "value": 168.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 76.0, 82.0, 76.0, 82.0, 86.0, 81.0, 80.0, 76.0, 74.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 79.0, 78.0, 87.0, 82.0, 82.0, 88.0, 85.0, 81.0], "value": 83.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 119.0, 118.0, 116.0, 118.0, 115.0, 116.0, 118.0, 115.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 154.0, 153.0, 154.0, 151.0, 148.0, 150.0, 152.0, 148.0, 153.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 97.0, 94.0, 101.0, 94.0, 95.0, 105.0, 100.0], "value": 97.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 62.0, 53.0, 54.0, 54.0, 57.0, 58.0, 52.0, 53.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 49.0, 39.0, 46.0, 44.0, 38.0, 40.0, 38.0, 39.0, 38.0], "value": 41.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 87.0, 76.0, 80.0, 78.0, 79.0, 71.0, 72.0, 74.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 112.0, 115.0, 121.0, 120.0, 112.0, 122.0, 118.0, 120.0], "value": 117.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 181.0, 170.0, 168.0, 176.0, 178.0, 170.0, 183.0, 176.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 65.0, 74.0, 67.0, 69.0, 67.0, 65.0, 67.0, 70.0], "value": 68.0, "unit": "score"}]}]} 07:34:39 INFO - Return code: 0 07:34:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:34:39 INFO - # TBPL SUCCESS # 07:34:39 INFO - Running post-action listener: _package_coverage_data 07:34:39 INFO - Running post-action listener: _resource_record_post_action 07:34:39 INFO - [mozharness: 2017-09-21 14:34:39.127556Z] Finished run-tests step (success) 07:34:39 INFO - Running post-run listener: _resource_record_post_run 07:34:39 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 8192; Write bytes: 342261760; Read time: 172; Write time: 173512 07:34:39 INFO - TinderboxPrint: CPU usage
13.3% 07:34:39 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 172 07:34:39 INFO - TinderboxPrint: I/O write bytes / time
342,261,760 / 173,512 07:34:39 INFO - TinderboxPrint: CPU idle
8,259.3 (86.7%) 07:34:39 INFO - TinderboxPrint: CPU user
1,201.4 (12.6%) 07:34:39 INFO - TinderboxPrint: Swap in / out
0 / 0 07:34:39 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10354688; Read time: 0; Write time: 704 07:34:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:34:39 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 331907072; Read time: 164; Write time: 172808 07:34:39 INFO - Running post-run listener: _upload_blobber_files 07:34:39 INFO - Blob upload gear active. 07:34:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:34:39 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 07:34:39 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'] 07:34:39 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 07:34:40 INFO - (blobuploader) - INFO - Open directory for files ... 07:34:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:34:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:34:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:41 INFO - (blobuploader) - INFO - Done attempting. 07:34:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:34:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:34:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:42 INFO - (blobuploader) - INFO - Done attempting. 07:34:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:34:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:34:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:45 INFO - (blobuploader) - INFO - Done attempting. 07:34:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:34:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:34:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:46 INFO - (blobuploader) - INFO - Done attempting. 07:34:46 INFO - (blobuploader) - INFO - Iteration through files over. 07:34:46 INFO - Return code: 0 07:34:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:34:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:34:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bb4a089c0fcdd8922a5f452ff1540ef2920afc3dad91512c890584c53787d7158725acc7ecb8ba33fcc0a313957e2c2e2e0498c9e4408eb2f6ccc4c9c2a0b73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f042f8121293e0d1d1f88d4cf2c8347223ac3033ef6896e67f2be1d9cb426edae3c9542c552ecc9c6bf9cb26f297c3ad648f1b733d9cac0de8b12eda48b52b9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c73f6e2e8312b64c55434d1492017d47f67b4c4d7aa7516b61d0496458e8fa1a6bade48da8620ccc0d25c895cad7711cd8e94412580e828c29789b626a1e77d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5437eb58455815be3a69544863d448b8b0decbafe22b5e7e0951e6adddfd3f896c504072ab00808859f7c8f7deab8c45910548992f546ed9775fca138775a800"} 07:34:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:34:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:34:46 INFO - Contents: 07:34:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bb4a089c0fcdd8922a5f452ff1540ef2920afc3dad91512c890584c53787d7158725acc7ecb8ba33fcc0a313957e2c2e2e0498c9e4408eb2f6ccc4c9c2a0b73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f042f8121293e0d1d1f88d4cf2c8347223ac3033ef6896e67f2be1d9cb426edae3c9542c552ecc9c6bf9cb26f297c3ad648f1b733d9cac0de8b12eda48b52b9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c73f6e2e8312b64c55434d1492017d47f67b4c4d7aa7516b61d0496458e8fa1a6bade48da8620ccc0d25c895cad7711cd8e94412580e828c29789b626a1e77d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5437eb58455815be3a69544863d448b8b0decbafe22b5e7e0951e6adddfd3f896c504072ab00808859f7c8f7deab8c45910548992f546ed9775fca138775a800"} 07:34:46 INFO - Running post-run listener: copy_logs_to_upload_dir 07:34:46 INFO - Copying logs to upload dir... 07:34:46 INFO - mkdir: /builds/slave/test/build/upload/logs 07:34:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.427547 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-21 07:34:46.694152) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 07:34:46.699085) ========= 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=483e410453545d07cca6935c000001ab-1506002765.801343-1765993885 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/8bb4a089c0fcdd8922a5f452ff1540ef2920afc3dad91512c890584c53787d7158725acc7ecb8ba33fcc0a313957e2c2e2e0498c9e4408eb2f6ccc4c9c2a0b73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f042f8121293e0d1d1f88d4cf2c8347223ac3033ef6896e67f2be1d9cb426edae3c9542c552ecc9c6bf9cb26f297c3ad648f1b733d9cac0de8b12eda48b52b9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c73f6e2e8312b64c55434d1492017d47f67b4c4d7aa7516b61d0496458e8fa1a6bade48da8620ccc0d25c895cad7711cd8e94412580e828c29789b626a1e77d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5437eb58455815be3a69544863d448b8b0decbafe22b5e7e0951e6adddfd3f896c504072ab00808859f7c8f7deab8c45910548992f546ed9775fca138775a800"} build_url:https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011483 build_url: 'https://queue.taskcluster.net/v1/task/OYLYgPYVTVKEo2gH9bptQA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bb4a089c0fcdd8922a5f452ff1540ef2920afc3dad91512c890584c53787d7158725acc7ecb8ba33fcc0a313957e2c2e2e0498c9e4408eb2f6ccc4c9c2a0b73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f042f8121293e0d1d1f88d4cf2c8347223ac3033ef6896e67f2be1d9cb426edae3c9542c552ecc9c6bf9cb26f297c3ad648f1b733d9cac0de8b12eda48b52b9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c73f6e2e8312b64c55434d1492017d47f67b4c4d7aa7516b61d0496458e8fa1a6bade48da8620ccc0d25c895cad7711cd8e94412580e828c29789b626a1e77d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5437eb58455815be3a69544863d448b8b0decbafe22b5e7e0951e6adddfd3f896c504072ab00808859f7c8f7deab8c45910548992f546ed9775fca138775a800"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 07:34:46.728085) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:34:46.728410) ========= 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=483e410453545d07cca6935c000001ab-1506002765.801343-1765993885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004786 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:34:46.815641) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 07:34:46.815957) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 07:34:46.816280) ========= ========= Total master_lag: 0.37 =========