builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-016 starttime: 1504741193.28 results: success (0) revision: d8e238b811d3dc74515065ae8cab6c74baf0295f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 16:39:53.283992) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 16:39:53.284436) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 16:39:53.284713) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 16:39:53.302905) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 16:39:53.303288) ========= 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=00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-06 16:39:53-- 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% 69.6M=0s 2017-09-06 16:39:53 (69.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120582 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 16:39:53.440617) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 16:39:53.440928) ========= 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=00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015161 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 16:39:53.490363) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-06 16:39:53.490697) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d8e238b811d3dc74515065ae8cab6c74baf0295f --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 d8e238b811d3dc74515065ae8cab6c74baf0295f --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=00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-06 16:39:53,547 truncating revision to first 12 chars 2017-09-06 16:39:53,547 Setting DEBUG logging. 2017-09-06 16:39:53,547 attempt 1/10 2017-09-06 16:39:53,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d8e238b811d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-06 16:39:54,532 attempt 1/10 2017-09-06 16:39:55,126 current task status: no status available at this point. state: STARTED 2017-09-06 16:39:55,126 sleeping for 10.00s (attempt 1/10) 2017-09-06 16:40:05,136 attempt 2/10 2017-09-06 16:40:05,547 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-06 16:40:05,742 unpacking tar archive at: mozilla-central-d8e238b811d3/testing/mozharness/ program finished with exit code 0 elapsedTime=12.514904 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-06 16:40:06.021290) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 16:40:06.021629) ========= script_repo_revision: d8e238b811d3dc74515065ae8cab6c74baf0295f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 16:40:06.022071) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 16:40:06.022398) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 16:40:06.034989) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-06 16:40:06.035358) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:40:06 INFO - MultiFileLogger online at 20170906 16:40:06 in /builds/slave/test 16:40:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:40:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:40:06 INFO - {'append_to_log': False, 16:40:06 INFO - 'base_work_dir': '/builds/slave/test', 16:40:06 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:40:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:40:06 INFO - 'branch': 'Firefox-Non-PGO', 16:40:06 INFO - 'buildbot_json_path': 'buildprops.json', 16:40:06 INFO - 'code_coverage': False, 16:40:06 INFO - 'config_files': ('talos/linux_config.py',), 16:40:06 INFO - 'default_actions': ('clobber', 16:40:06 INFO - 'read-buildbot-config', 16:40:06 INFO - 'download-and-extract', 16:40:06 INFO - 'populate-webroot', 16:40:06 INFO - 'create-virtualenv', 16:40:06 INFO - 'install', 16:40:06 INFO - 'setup-mitmproxy', 16:40:06 INFO - 'run-tests'), 16:40:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:40:06 INFO - 'disable_ccov_upload': False, 16:40:06 INFO - 'disable_stylo': False, 16:40:06 INFO - 'download_minidump_stackwalk': True, 16:40:06 INFO - 'download_symbols': 'ondemand', 16:40:06 INFO - 'enable_stylo': False, 16:40:06 INFO - 'enable_webrender': False, 16:40:06 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:40:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:40:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:40:06 INFO - 'gecko_profile': False, 16:40:06 INFO - 'gecko_profile_interval': 0, 16:40:06 INFO - 'installer_path': 'installer.exe', 16:40:06 INFO - 'log_level': 'info', 16:40:06 INFO - 'log_name': 'talos', 16:40:06 INFO - 'log_to_console': True, 16:40:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:40:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:40:06 INFO - 'opt_config_files': (), 16:40:06 INFO - 'pip_index': False, 16:40:06 INFO - 'suite': 'dromaeojs-e10s', 16:40:06 INFO - 'system_bits': '32', 16:40:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:40:06 INFO - 'title': 'talos-linux64-ix-016', 16:40:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:40:06 INFO - 'use_talos_json': True, 16:40:06 INFO - 'verify': 'False', 16:40:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:40:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:40:06 INFO - 'work_dir': 'build'} 16:40:06 INFO - [mozharness: 2017-09-06 23:40:06.235451Z] Running clobber step. 16:40:06 INFO - Running pre-action listener: _resource_record_pre_action 16:40:06 INFO - Running main action method: clobber 16:40:06 INFO - rmtree: /builds/slave/test/build 16:40:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:40:07 INFO - Running post-action listener: _resource_record_post_action 16:40:07 INFO - [mozharness: 2017-09-06 23:40:07.258972Z] Finished clobber step (success) 16:40:07 INFO - [mozharness: 2017-09-06 23:40:07.259070Z] Running read-buildbot-config step. 16:40:07 INFO - Running pre-action listener: _resource_record_pre_action 16:40:07 INFO - Running main action method: read_buildbot_config 16:40:07 INFO - Using buildbot properties: 16:40:07 INFO - { 16:40:07 INFO - "project": "", 16:40:07 INFO - "product": "firefox", 16:40:07 INFO - "who": "kwierso@gmail.com", 16:40:07 INFO - "installer_path": "public/build/target.tar.bz2", 16:40:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:40:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:40:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:40:07 INFO - "stage_platform": "linux64", 16:40:07 INFO - "basedir": "/builds/slave/test", 16:40:07 INFO - "buildnumber": 1119, 16:40:07 INFO - "platform": "ubuntu64_hw", 16:40:07 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:40:07 INFO - "slavebuilddir": "test", 16:40:07 INFO - "taskId": "FbLJS9wQRbqfYZdQYD6_RA", 16:40:07 INFO - "branch": "mozilla-central", 16:40:07 INFO - "script_repo_revision": "production", 16:40:07 INFO - "revision": "d8e238b811d3dc74515065ae8cab6c74baf0295f", 16:40:07 INFO - "slavename": "talos-linux64-ix-016", 16:40:07 INFO - "repo_path": "mozilla-central" 16:40:07 INFO - } 16:40:07 INFO - Finding installer, test and symbols from parent task. 16:40:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FbLJS9wQRbqfYZdQYD6_RA'}, attempt #1 16:40:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FbLJS9wQRbqfYZdQYD6_RA'}, attempt #1 16:40:08 INFO - Task dependencies: UKt4CYvxSaGZCXqv81saJw 16:40:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw'}, attempt #1 16:40:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 16:40:08 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.test_packages.json 16:40:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.crashreporter-symbols.zip 16:40:08 INFO - Running post-action listener: _resource_record_post_action 16:40:08 INFO - [mozharness: 2017-09-06 23:40:08.649176Z] Finished read-buildbot-config step (success) 16:40:08 INFO - [mozharness: 2017-09-06 23:40:08.649441Z] Running download-and-extract step. 16:40:08 INFO - Running pre-action listener: _resource_record_pre_action 16:40:08 INFO - Running main action method: download_and_extract 16:40:08 INFO - mkdir: /builds/slave/test/build/tests 16:40: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')]} 16:40:08 INFO - trying https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.test_packages.json 16:40:08 INFO - Downloading https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:40:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:40:10 INFO - Downloaded 989 bytes. 16:40:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:40:10 INFO - Using the following test package requirements: 16:40:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:40:10 INFO - u'common': [u'target.common.tests.zip'], 16:40:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:40:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:40:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:40:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:40:10 INFO - u'mozbase': [u'target.common.tests.zip'], 16:40:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:40:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:40:10 INFO - u'web-platform': [u'target.common.tests.zip', 16:40:10 INFO - u'target.web-platform.tests.tar.gz'], 16:40:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:40:10 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:40:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip 16:40:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:40:10 INFO - Fetch https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip into memory 16:40:11 INFO - Content-Length response header: 55021102 16:40:11 INFO - Bytes received: 55021102 16:40:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:40:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip 16:40:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:40:22 INFO - Fetch https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.common.tests.zip into memory 16:40:24 INFO - Content-Length response header: 55021102 16:40:24 INFO - Bytes received: 55021102 16:40:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.talos.tests.zip 16:40:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:40:36 INFO - Fetch https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.talos.tests.zip into memory 16:40:37 INFO - Content-Length response header: 14496665 16:40:37 INFO - Bytes received: 14496665 16:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:37 INFO - trying https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 16:40:37 INFO - Downloading https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:40:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:40:41 INFO - Downloaded 62756961 bytes. 16:40:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 16:40:41 INFO - mkdir: /builds/slave/test/properties 16:40:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:40:41 INFO - Writing to file /builds/slave/test/properties/build_url 16:40:41 INFO - Contents: 16:40:41 INFO - build_url:https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 16:40:41 INFO - Running post-action listener: _resource_record_post_action 16:40:41 INFO - Running post-action listener: find_tests_for_verification 16:40:41 INFO - Running post-action listener: set_extra_try_arguments 16:40:41 INFO - [mozharness: 2017-09-06 23:40:41.306531Z] Finished download-and-extract step (success) 16:40:41 INFO - [mozharness: 2017-09-06 23:40:41.306661Z] Running populate-webroot step. 16:40:41 INFO - Running pre-action listener: _resource_record_pre_action 16:40:41 INFO - Running main action method: populate_webroot 16:40:41 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:40:41 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['tresize', 16:40:41 INFO - 'tcanvasmark']}, 16:40:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:40:41 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['dromaeo_css', 16:40:41 INFO - 'kraken']}, 16:40:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:40:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:40:41 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:40:41 INFO - 'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:40:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:40:41 INFO - 'tests': ['damp', 'tps']}, 16:40:41 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:40:41 INFO - 'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['damp', 'tps']}, 16:40:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:40:41 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['dromaeo_dom']}, 16:40:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:40:41 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['basic_compositor_video', 16:40:41 INFO - 'glvideo']}, 16:40:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:40:41 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:40:41 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:40:41 INFO - 'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['ts_paint_webext', 16:40:41 INFO - 'tp5o_webext']}, 16:40:41 INFO - 'other-e10s': {'tests': ['a11yr', 16:40:41 INFO - 'ts_paint', 16:40:41 INFO - 'tpaint', 16:40:41 INFO - 'sessionrestore', 16:40:41 INFO - 'sessionrestore_many_windows', 16:40:41 INFO - 'sessionrestore_no_auto_restore', 16:40:41 INFO - 'tabpaint']}, 16:40:41 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['a11yr', 16:40:41 INFO - 'ts_paint', 16:40:41 INFO - 'tpaint', 16:40:41 INFO - 'sessionrestore', 16:40:41 INFO - 'sessionrestore_many_windows', 16:40:41 INFO - 'sessionrestore_no_auto_restore', 16:40:41 INFO - 'tabpaint']}, 16:40:41 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:40:41 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:40:41 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['bloom_basic_singleton']}, 16:40:41 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['bloom_basic']}, 16:40:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:40:41 INFO - 'tsvgr_opacity', 16:40:41 INFO - 'tart', 16:40:41 INFO - 'tscrollx', 16:40:41 INFO - 'tsvg_static']}, 16:40:41 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['tsvgx', 16:40:41 INFO - 'tsvgr_opacity', 16:40:41 INFO - 'tart', 16:40:41 INFO - 'tscrollx', 16:40:41 INFO - 'tsvg_static']}, 16:40:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:40:41 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:40:41 INFO - 'talos_options': ['--disable-stylo'], 16:40:41 INFO - 'tests': ['tp5o']}, 16:40:41 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:40:41 INFO - 'talos_options': ['--mitmproxy', 16:40:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:40:41 INFO - '--firstNonBlankPaint'], 16:40:41 INFO - 'tests': ['tp6_google', 16:40:41 INFO - 'tp6_youtube', 16:40:41 INFO - 'tp6_amazon', 16:40:41 INFO - 'tp6_facebook']}, 16:40:41 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:40:41 INFO - 'talos_options': ['--disable-stylo', 16:40:41 INFO - '--mitmproxy', 16:40:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:40:41 INFO - '--firstNonBlankPaint'], 16:40:41 INFO - 'tests': ['tp6_google', 16:40:41 INFO - 'tp6_youtube', 16:40:41 INFO - 'tp6_amazon', 16:40:41 INFO - 'tp6_facebook']}, 16:40:41 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:40:41 INFO - 'talos_options': ['--stylo-threads=1', 16:40:41 INFO - '--mitmproxy', 16:40:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:40:41 INFO - '--firstNonBlankPaint'], 16:40:41 INFO - 'tests': ['tp6_google', 16:40:41 INFO - 'tp6_youtube', 16:40:41 INFO - 'tp6_amazon', 16:40:41 INFO - 'tp6_facebook']}, 16:40:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:40:41 INFO - 'talos_options': ['--xperf_path', 16:40:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:40:41 INFO - 'tests': ['tp5n']}, 16:40:41 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:40:41 INFO - 'talos_options': ['--disable-stylo', 16:40:41 INFO - '--xperf_path', 16:40:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:40:41 INFO - 'tests': ['tp5n']}}} 16:40:41 INFO - Running post-action listener: _resource_record_post_action 16:40:41 INFO - [mozharness: 2017-09-06 23:40:41.322308Z] Finished populate-webroot step (success) 16:40:41 INFO - [mozharness: 2017-09-06 23:40:41.322397Z] Running create-virtualenv step. 16:40:41 INFO - Running pre-action listener: _resource_record_pre_action 16:40:41 INFO - Running main action method: create_virtualenv 16:40:41 INFO - Creating virtualenv /builds/slave/test/build/venv 16:40:41 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:40:41 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:40:41 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:40:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:40:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:40:41 INFO - Installing distribute.............................................................................................................................................................................................done. 16:40:42 INFO - Installing pip...............done. 16:40:42 INFO - Return code: 0 16:40:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:40:42 INFO - Reading from file tmpfile_stdout 16:40:42 INFO - Output received: 16:40:42 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:40:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1889a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, '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': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:40:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:40:42 INFO - Using env: {'DISPLAY': ':0', 16:40:42 INFO - 'HOME': '/home/cltbld', 16:40:42 INFO - 'LANG': 'en_US.UTF-8', 16:40:42 INFO - 'LANGUAGE': 'en_US:en', 16:40:42 INFO - 'LOGNAME': 'cltbld', 16:40:42 INFO - 'MAIL': '/var/mail/cltbld', 16:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:42 INFO - 'MOZ_NO_REMOTE': '1', 16:40:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:42 INFO - 'NO_EM_RESTART': '1', 16:40:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:42 INFO - 'PWD': '/builds/slave/test', 16:40:42 INFO - 'SHELL': '/bin/bash', 16:40:42 INFO - 'SHLVL': '1', 16:40:42 INFO - 'TERM': 'linux', 16:40:42 INFO - 'TMOUT': '86400', 16:40:42 INFO - 'USER': 'cltbld', 16:40:42 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:42 INFO - '_': '/tools/buildbot/bin/python'} 16:40:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:40:43 INFO - Downloading/unpacking pip>=1.5 16:40:43 INFO - Running setup.py egg_info for package pip 16:40:43 INFO - warning: no files found matching 'pip/cacert.pem' 16:40:43 INFO - warning: no files found matching '*.html' under directory 'docs' 16:40:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:40:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:40:43 INFO - Installing collected packages: pip 16:40:43 INFO - Found existing installation: pip 1.1 16:40:43 INFO - Uninstalling pip: 16:40:43 INFO - Successfully uninstalled pip 16:40:43 INFO - Running setup.py install for pip 16:40:43 INFO - warning: no files found matching 'pip/cacert.pem' 16:40:43 INFO - warning: no files found matching '*.html' under directory 'docs' 16:40:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:40:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:40:43 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:40:43 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:40:43 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:40:43 INFO - Successfully installed pip 16:40:43 INFO - Cleaning up... 16:40:43 INFO - Return code: 0 16:40:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:40: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')]} 16:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40: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')]} 16:40:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40: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', '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 0x16c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1889a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, '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': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:40: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:40: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 psutil>=3.1.1 16:40:43 INFO - Using env: {'DISPLAY': ':0', 16:40:43 INFO - 'HOME': '/home/cltbld', 16:40:43 INFO - 'LANG': 'en_US.UTF-8', 16:40:43 INFO - 'LANGUAGE': 'en_US:en', 16:40:43 INFO - 'LOGNAME': 'cltbld', 16:40:43 INFO - 'MAIL': '/var/mail/cltbld', 16:40:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:43 INFO - 'MOZ_NO_REMOTE': '1', 16:40:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:43 INFO - 'NO_EM_RESTART': '1', 16:40:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:43 INFO - 'PWD': '/builds/slave/test', 16:40:43 INFO - 'SHELL': '/bin/bash', 16:40:43 INFO - 'SHLVL': '1', 16:40:43 INFO - 'TERM': 'linux', 16:40:43 INFO - 'TMOUT': '86400', 16:40:43 INFO - 'USER': 'cltbld', 16:40:43 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:40:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:43 INFO - '_': '/tools/buildbot/bin/python'} 16:40:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:43 INFO - Downloading/unpacking psutil>=3.1.1 16:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:40:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:40:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:40:44 INFO - Installing collected packages: psutil 16:40:44 INFO - Running setup.py install for psutil 16:40:44 INFO - building 'psutil._psutil_linux' extension 16:40:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:40:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:40:45 INFO - building 'psutil._psutil_posix' extension 16:40:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:40:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:40:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:40:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:40:45 INFO - Successfully installed psutil 16:40:45 INFO - Cleaning up... 16:40:45 INFO - Return code: 0 16:40:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:40:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:45 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 0x16c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1889a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, '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': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:40:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:40:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:40:45 INFO - Using env: {'DISPLAY': ':0', 16:40:45 INFO - 'HOME': '/home/cltbld', 16:40:45 INFO - 'LANG': 'en_US.UTF-8', 16:40:45 INFO - 'LANGUAGE': 'en_US:en', 16:40:45 INFO - 'LOGNAME': 'cltbld', 16:40:45 INFO - 'MAIL': '/var/mail/cltbld', 16:40:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:45 INFO - 'MOZ_NO_REMOTE': '1', 16:40:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:45 INFO - 'NO_EM_RESTART': '1', 16:40:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:45 INFO - 'PWD': '/builds/slave/test', 16:40:45 INFO - 'SHELL': '/bin/bash', 16:40:45 INFO - 'SHLVL': '1', 16:40:45 INFO - 'TERM': 'linux', 16:40:45 INFO - 'TMOUT': '86400', 16:40:45 INFO - 'USER': 'cltbld', 16:40:45 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:40:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:45 INFO - '_': '/tools/buildbot/bin/python'} 16:40:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:40:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:40:46 INFO - Installing collected packages: mozsystemmonitor 16:40:46 INFO - Running setup.py install for mozsystemmonitor 16:40:46 INFO - Successfully installed mozsystemmonitor 16:40:46 INFO - Cleaning up... 16:40:46 INFO - Return code: 0 16:40:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:46 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 0x16c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1889a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, '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': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:40:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:40:46 INFO - Using env: {'DISPLAY': ':0', 16:40:46 INFO - 'HOME': '/home/cltbld', 16:40:46 INFO - 'LANG': 'en_US.UTF-8', 16:40:46 INFO - 'LANGUAGE': 'en_US:en', 16:40:46 INFO - 'LOGNAME': 'cltbld', 16:40:46 INFO - 'MAIL': '/var/mail/cltbld', 16:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:46 INFO - 'MOZ_NO_REMOTE': '1', 16:40:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:46 INFO - 'NO_EM_RESTART': '1', 16:40:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:46 INFO - 'PWD': '/builds/slave/test', 16:40:46 INFO - 'SHELL': '/bin/bash', 16:40:46 INFO - 'SHLVL': '1', 16:40:46 INFO - 'TERM': 'linux', 16:40:46 INFO - 'TMOUT': '86400', 16:40:46 INFO - 'USER': 'cltbld', 16:40:46 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:46 INFO - '_': '/tools/buildbot/bin/python'} 16:40:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:47 INFO - Downloading/unpacking jsonschema==2.5.1 16:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:40:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:48 INFO - Downloading functools32-3.2.3-2.tar.gz 16:40:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:40:48 INFO - warning: no files found matching '*.txt' 16:40:48 INFO - no previously-included directories found matching 'build' 16:40:48 INFO - no previously-included directories found matching 'dist' 16:40:48 INFO - no previously-included directories found matching '.git*' 16:40:48 INFO - Installing collected packages: jsonschema, functools32 16:40:48 INFO - Running setup.py install for functools32 16:40:48 INFO - warning: no files found matching '*.txt' 16:40:48 INFO - no previously-included directories found matching 'build' 16:40:48 INFO - no previously-included directories found matching 'dist' 16:40:48 INFO - no previously-included directories found matching '.git*' 16:40:48 INFO - Successfully installed jsonschema functools32 16:40:48 INFO - Cleaning up... 16:40:48 INFO - Return code: 0 16:40:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1889a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, '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': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:40:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:40:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:40:48 INFO - Using env: {'DISPLAY': ':0', 16:40:48 INFO - 'HOME': '/home/cltbld', 16:40:48 INFO - 'LANG': 'en_US.UTF-8', 16:40:48 INFO - 'LANGUAGE': 'en_US:en', 16:40:48 INFO - 'LOGNAME': 'cltbld', 16:40:48 INFO - 'MAIL': '/var/mail/cltbld', 16:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:48 INFO - 'MOZ_NO_REMOTE': '1', 16:40:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:48 INFO - 'NO_EM_RESTART': '1', 16:40:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:48 INFO - 'PWD': '/builds/slave/test', 16:40:48 INFO - 'SHELL': '/bin/bash', 16:40:48 INFO - 'SHLVL': '1', 16:40:48 INFO - 'TERM': 'linux', 16:40:48 INFO - 'TMOUT': '86400', 16:40:48 INFO - 'USER': 'cltbld', 16:40:48 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:48 INFO - '_': '/tools/buildbot/bin/python'} 16:40:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:40:48 INFO - Cleaning up... 16:40:48 INFO - Return code: 0 16:40:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1889a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, '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': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:40:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:40:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:40:48 INFO - Using env: {'DISPLAY': ':0', 16:40:48 INFO - 'HOME': '/home/cltbld', 16:40:48 INFO - 'LANG': 'en_US.UTF-8', 16:40:48 INFO - 'LANGUAGE': 'en_US:en', 16:40:48 INFO - 'LOGNAME': 'cltbld', 16:40:48 INFO - 'MAIL': '/var/mail/cltbld', 16:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:48 INFO - 'MOZ_NO_REMOTE': '1', 16:40:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:48 INFO - 'NO_EM_RESTART': '1', 16:40:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:48 INFO - 'PWD': '/builds/slave/test', 16:40:48 INFO - 'SHELL': '/bin/bash', 16:40:48 INFO - 'SHLVL': '1', 16:40:48 INFO - 'TERM': 'linux', 16:40:48 INFO - 'TMOUT': '86400', 16:40:48 INFO - 'USER': 'cltbld', 16:40:48 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:48 INFO - '_': '/tools/buildbot/bin/python'} 16:40:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:49 INFO - Downloading/unpacking blobuploader==1.2.4 16:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:50 INFO - Downloading blobuploader-1.2.4.tar.gz 16:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:40:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:40:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:50 INFO - Downloading docopt-0.6.1.tar.gz 16:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:40:50 INFO - Installing collected packages: blobuploader, requests, docopt 16:40:50 INFO - Running setup.py install for blobuploader 16:40:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:40:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:40:50 INFO - Running setup.py install for requests 16:40:51 INFO - Running setup.py install for docopt 16:40:51 INFO - Successfully installed blobuploader requests docopt 16:40:51 INFO - Cleaning up... 16:40:51 INFO - Return code: 0 16:40:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:51 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 0x16c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1889a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, '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': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:40:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:40:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:40:51 INFO - Using env: {'DISPLAY': ':0', 16:40:51 INFO - 'HOME': '/home/cltbld', 16:40:51 INFO - 'LANG': 'en_US.UTF-8', 16:40:51 INFO - 'LANGUAGE': 'en_US:en', 16:40:51 INFO - 'LOGNAME': 'cltbld', 16:40:51 INFO - 'MAIL': '/var/mail/cltbld', 16:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:51 INFO - 'MOZ_NO_REMOTE': '1', 16:40:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:51 INFO - 'NO_EM_RESTART': '1', 16:40:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:51 INFO - 'PWD': '/builds/slave/test', 16:40:51 INFO - 'SHELL': '/bin/bash', 16:40:51 INFO - 'SHLVL': '1', 16:40:51 INFO - 'TERM': 'linux', 16:40:51 INFO - 'TMOUT': '86400', 16:40:51 INFO - 'USER': 'cltbld', 16:40:51 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:51 INFO - '_': '/tools/buildbot/bin/python'} 16:40:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:40:51 INFO - Running setup.py (path:/tmp/pip-oRCzjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:40:51 INFO - Running setup.py (path:/tmp/pip-jnOQ8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:40:51 INFO - Running setup.py (path:/tmp/pip-R3hSKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:40:51 INFO - Running setup.py (path:/tmp/pip-pVUwR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:40:51 INFO - Running setup.py (path:/tmp/pip-YvhILT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:40:51 INFO - Running setup.py (path:/tmp/pip-BoOFEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:40:52 INFO - Running setup.py (path:/tmp/pip-CMgZEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:40:52 INFO - Running setup.py (path:/tmp/pip-4hc8WD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:40:52 INFO - Running setup.py (path:/tmp/pip-PVUG_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:40:52 INFO - Running setup.py (path:/tmp/pip-x8TTuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:40:52 INFO - Running setup.py (path:/tmp/pip-u3oGQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:40:52 INFO - Running setup.py (path:/tmp/pip-3QzWBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:40:52 INFO - Running setup.py (path:/tmp/pip-urye5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:40:52 INFO - Running setup.py (path:/tmp/pip-qMLorF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:52 INFO - Running setup.py (path:/tmp/pip-71mDOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:40:52 INFO - Running setup.py (path:/tmp/pip-iz4VvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:40:52 INFO - Running setup.py (path:/tmp/pip-H0760X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:40:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:40:52 INFO - Running setup.py install for manifestparser 16:40:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:40:52 INFO - Running setup.py install for mozcrash 16:40:52 INFO - Running setup.py install for mozdebug 16:40:53 INFO - Running setup.py install for mozdevice 16:40:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:40:53 INFO - Running setup.py install for mozfile 16:40:53 INFO - Running setup.py install for mozhttpd 16:40:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:40:53 INFO - Running setup.py install for mozinfo 16:40:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:40:53 INFO - Running setup.py install for mozInstall 16:40:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:40:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:40:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:40:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:40:53 INFO - Running setup.py install for mozleak 16:40:53 INFO - Running setup.py install for mozlog 16:40:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:40:53 INFO - Running setup.py install for moznetwork 16:40:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:40:53 INFO - Running setup.py install for mozprocess 16:40:54 INFO - Running setup.py install for mozprofile 16:40:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:40:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:40:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:40:54 INFO - Running setup.py install for mozrunner 16:40:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:40:54 INFO - Running setup.py install for mozscreenshot 16:40:54 INFO - Running setup.py install for moztest 16:40:54 INFO - Running setup.py install for mozversion 16:40:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:40:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:40:54 INFO - Cleaning up... 16:40:54 INFO - Return code: 0 16:40:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:54 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 0x16c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1889a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, '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': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:40:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:40:54 INFO - Using env: {'DISPLAY': ':0', 16:40:54 INFO - 'HOME': '/home/cltbld', 16:40:54 INFO - 'LANG': 'en_US.UTF-8', 16:40:54 INFO - 'LANGUAGE': 'en_US:en', 16:40:54 INFO - 'LOGNAME': 'cltbld', 16:40:54 INFO - 'MAIL': '/var/mail/cltbld', 16:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:54 INFO - 'MOZ_NO_REMOTE': '1', 16:40:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:54 INFO - 'NO_EM_RESTART': '1', 16:40:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:54 INFO - 'PWD': '/builds/slave/test', 16:40:54 INFO - 'SHELL': '/bin/bash', 16:40:54 INFO - 'SHLVL': '1', 16:40:54 INFO - 'TERM': 'linux', 16:40:54 INFO - 'TMOUT': '86400', 16:40:54 INFO - 'USER': 'cltbld', 16:40:54 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:54 INFO - '_': '/tools/buildbot/bin/python'} 16:40:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:40:54 INFO - Running setup.py (path:/tmp/pip-weJ8Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:40:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:40:54 INFO - Running setup.py (path:/tmp/pip-xMxiLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:40:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:40:54 INFO - Running setup.py (path:/tmp/pip-CAoUeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:40:55 INFO - Running setup.py (path:/tmp/pip-QQ6FSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:40:55 INFO - Running setup.py (path:/tmp/pip-RCh4Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:40:55 INFO - Running setup.py (path:/tmp/pip-a5U6tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:40:55 INFO - Running setup.py (path:/tmp/pip-W0zUhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:40:55 INFO - Running setup.py (path:/tmp/pip-70A27s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:40:55 INFO - Running setup.py (path:/tmp/pip-Zbug1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:40:55 INFO - Running setup.py (path:/tmp/pip-nftiIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:40:55 INFO - Running setup.py (path:/tmp/pip-BKAGyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:40:55 INFO - Running setup.py (path:/tmp/pip-TVLMUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:40:55 INFO - Running setup.py (path:/tmp/pip-Q74i8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:40:55 INFO - Running setup.py (path:/tmp/pip-RSXLEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:55 INFO - Running setup.py (path:/tmp/pip-Ci515X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:40:55 INFO - Running setup.py (path:/tmp/pip-MaPW7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:40:55 INFO - Running setup.py (path:/tmp/pip-qUCXqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:40:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:57 INFO - Downloading blessings-1.6.tar.gz 16:40:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:40:57 INFO - Installing collected packages: blessings 16:40:57 INFO - Running setup.py install for blessings 16:40:57 INFO - Successfully installed blessings 16:40:57 INFO - Cleaning up... 16:40:57 INFO - Return code: 0 16:40:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:40:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:40:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:40:57 INFO - Reading from file tmpfile_stdout 16:40:57 INFO - Current package versions: 16:40:57 INFO - argparse == 1.2.1 16:40:57 INFO - blessings == 1.6 16:40:57 INFO - blobuploader == 1.2.4 16:40:57 INFO - docopt == 0.6.1 16:40:57 INFO - functools32 == 3.2.3-2 16:40:57 INFO - jsonschema == 2.5.1 16:40:57 INFO - manifestparser == 1.1 16:40:57 INFO - mozInstall == 1.13 16:40:57 INFO - mozcrash == 1.0 16:40:57 INFO - mozdebug == 0.1 16:40:57 INFO - mozdevice == 0.51 16:40:57 INFO - mozfile == 1.2 16:40:57 INFO - mozhttpd == 0.7 16:40:57 INFO - mozinfo == 0.10 16:40:57 INFO - mozleak == 0.1 16:40:57 INFO - mozlog == 3.5 16:40:57 INFO - moznetwork == 0.27 16:40:57 INFO - mozprocess == 0.25 16:40:57 INFO - mozprofile == 0.28 16:40:57 INFO - mozrunner == 6.13 16:40:57 INFO - mozscreenshot == 0.1 16:40:57 INFO - mozsystemmonitor == 0.3 16:40:57 INFO - moztest == 0.8 16:40:57 INFO - mozversion == 1.4 16:40:57 INFO - psutil == 3.1.1 16:40:57 INFO - requests == 1.2.3 16:40:57 INFO - wsgiref == 0.1.2 16:40:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:57 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 0x16c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1889a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, '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': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:40:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:40:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:40:57 INFO - Using env: {'DISPLAY': ':0', 16:40:57 INFO - 'HOME': '/home/cltbld', 16:40:57 INFO - 'LANG': 'en_US.UTF-8', 16:40:57 INFO - 'LANGUAGE': 'en_US:en', 16:40:57 INFO - 'LOGNAME': 'cltbld', 16:40:57 INFO - 'MAIL': '/var/mail/cltbld', 16:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:57 INFO - 'MOZ_NO_REMOTE': '1', 16:40:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:57 INFO - 'NO_EM_RESTART': '1', 16:40:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:57 INFO - 'PWD': '/builds/slave/test', 16:40:57 INFO - 'SHELL': '/bin/bash', 16:40:57 INFO - 'SHLVL': '1', 16:40:57 INFO - 'TERM': 'linux', 16:40:57 INFO - 'TMOUT': '86400', 16:40:57 INFO - 'USER': 'cltbld', 16:40:57 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:57 INFO - '_': '/tools/buildbot/bin/python'} 16:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:40:57 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:40:59 INFO - Installing collected packages: simplejson 16:40:59 INFO - Running setup.py install for simplejson 16:40:59 INFO - building 'simplejson._speedups' extension 16:40:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 16:40:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 16:41:00 INFO - Successfully installed simplejson 16:41:00 INFO - Cleaning up... 16:41:00 INFO - Return code: 0 16:41:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:41:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1889a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, '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': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:41:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:41:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:41:00 INFO - Using env: {'DISPLAY': ':0', 16:41:00 INFO - 'HOME': '/home/cltbld', 16:41:00 INFO - 'LANG': 'en_US.UTF-8', 16:41:00 INFO - 'LANGUAGE': 'en_US:en', 16:41:00 INFO - 'LOGNAME': 'cltbld', 16:41:00 INFO - 'MAIL': '/var/mail/cltbld', 16:41:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:00 INFO - 'MOZ_NO_REMOTE': '1', 16:41:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:00 INFO - 'NO_EM_RESTART': '1', 16:41:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:00 INFO - 'PWD': '/builds/slave/test', 16:41:00 INFO - 'SHELL': '/bin/bash', 16:41:00 INFO - 'SHLVL': '1', 16:41:00 INFO - 'TERM': 'linux', 16:41:00 INFO - 'TMOUT': '86400', 16:41:00 INFO - 'USER': 'cltbld', 16:41:00 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:41:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:00 INFO - '_': '/tools/buildbot/bin/python'} 16:41:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:41:00 INFO - Cleaning up... 16:41:00 INFO - Return code: 0 16:41:00 INFO - Running post-action listener: _resource_record_post_action 16:41:00 INFO - Running post-action listener: _start_resource_monitoring 16:41:00 INFO - Starting resource monitoring. 16:41:00 INFO - [mozharness: 2017-09-06 23:41:00.314405Z] Finished create-virtualenv step (success) 16:41:00 INFO - [mozharness: 2017-09-06 23:41:00.314873Z] Running install step. 16:41:00 INFO - Running pre-action listener: _resource_record_pre_action 16:41:00 INFO - Running main action method: install 16:41:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:00 INFO - Reading from file tmpfile_stdout 16:41:00 INFO - Detecting whether we're running mozinstall >=1.0... 16:41:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:41:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:41:00 INFO - Reading from file tmpfile_stdout 16:41:00 INFO - Output received: 16:41:00 INFO - Usage: mozinstall [options] installer 16:41:00 INFO - Options: 16:41:00 INFO - -h, --help show this help message and exit 16:41:00 INFO - -d DEST, --destination=DEST 16:41:00 INFO - Directory to install application into. [default: 16:41:00 INFO - "/builds/slave/test"] 16:41:00 INFO - --app=APP Application being installed. [default: firefox] 16:41:00 INFO - mkdir: /builds/slave/test/build/application 16:41:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:41:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:41:17 INFO - Reading from file tmpfile_stdout 16:41:17 INFO - Output received: 16:41:17 INFO - /builds/slave/test/build/application/firefox/firefox 16:41:17 INFO - Running post-action listener: _resource_record_post_action 16:41:17 INFO - [mozharness: 2017-09-06 23:41:17.680296Z] Finished install step (success) 16:41:17 INFO - [mozharness: 2017-09-06 23:41:17.680552Z] Running setup-mitmproxy step. 16:41:17 INFO - Running pre-action listener: _resource_record_pre_action 16:41:17 INFO - Running main action method: setup_mitmproxy 16:41:17 INFO - Skipping: mitmproxy is not required 16:41:17 INFO - Running post-action listener: _resource_record_post_action 16:41:17 INFO - [mozharness: 2017-09-06 23:41:17.681781Z] Finished setup-mitmproxy step (success) 16:41:17 INFO - [mozharness: 2017-09-06 23:41:17.682000Z] Running run-tests step. 16:41:17 INFO - Running pre-action listener: _resource_record_pre_action 16:41:17 INFO - Running pre-action listener: _set_gcov_prefix 16:41:17 INFO - Running main action method: run_tests 16:41:17 WARNING - Try message not found. 16:41:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:41:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:41:17 INFO - Python 2.7.3 16:41:17 INFO - Return code: 0 16:41:17 INFO - grabbing minidump binary from tooltool 16:41:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:17 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 0x1a30090>, '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 0x1a30d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2af90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:41:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:41:17 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:41:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:41:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:41:17 INFO - Return code: 0 16:41:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:41:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:41:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:41:17 INFO - ENV: RUST_BACKTRACE is now full 16:41:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:41:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:41:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:41:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:41:17 INFO - Using env: {'DISPLAY': ':0', 16:41:17 INFO - 'HOME': '/home/cltbld', 16:41:17 INFO - 'LANG': 'en_US.UTF-8', 16:41:17 INFO - 'LANGUAGE': 'en_US:en', 16:41:17 INFO - 'LOGNAME': 'cltbld', 16:41:17 INFO - 'MAIL': '/var/mail/cltbld', 16:41:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:41:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:41:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:17 INFO - 'MOZ_NO_REMOTE': '1', 16:41:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:41:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:17 INFO - 'NO_EM_RESTART': '1', 16:41:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:17 INFO - 'PWD': '/builds/slave/test', 16:41:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:41:17 INFO - 'RUST_BACKTRACE': 'full', 16:41:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:41:17 INFO - 'SHELL': '/bin/bash', 16:41:17 INFO - 'SHLVL': '1', 16:41:17 INFO - 'TERM': 'linux', 16:41:17 INFO - 'TMOUT': '86400', 16:41:17 INFO - 'USER': 'cltbld', 16:41:17 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:41:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:17 INFO - '_': '/tools/buildbot/bin/python'} 16:41:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:41:18 INFO - mozversion application_buildid: 20170906225137 16:41:18 INFO - mozversion application_changeset: d8e238b811d3dc74515065ae8cab6c74baf0295f 16:41:18 INFO - mozversion application_display_name: Nightly 16:41:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:41:18 INFO - mozversion application_name: Firefox 16:41:18 INFO - mozversion application_remotingname: firefox 16:41:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:41:18 INFO - mozversion application_vendor: Mozilla 16:41:18 INFO - mozversion application_version: 57.0a1 16:41:18 INFO - mozversion platform_buildid: 20170906225137 16:41:18 INFO - mozversion platform_changeset: d8e238b811d3dc74515065ae8cab6c74baf0295f 16:41:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:41:18 INFO - mozversion platform_version: 57.0a1 16:41:18 INFO - using testdate: 1504741278 16:41:18 INFO - actual date: 1504741278 16:41:18 INFO - starting webserver on 'localhost:57142' 16:41:18 INFO - SUITE-START | Running 2 tests 16:41:18 INFO - TEST-START | dromaeo_css 16:41:18 INFO - Initialising browser for dromaeo_css test... 16:41:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57142/getInfo.html -profile /tmp/tmp7_oGKF/profile 16:41:18 INFO - TEST-INFO | started process 7191 (/builds/slave/test/build/application/firefox/firefox http://localhost:57142/getInfo.html) 16:41:23 INFO - TEST-INFO | 7191: exit 0 16:41:23 INFO - Browser initialized. 16:41:23 INFO - Running cycle 1/1 for dromaeo_css test... 16:41:23 INFO - Using env: {'DISPLAY': ':0', 16:41:23 INFO - 'HOME': '/home/cltbld', 16:41:23 INFO - 'JSGC_DISABLE_POISONING': '1', 16:41:23 INFO - 'LANG': 'en_US.UTF-8', 16:41:23 INFO - 'LANGUAGE': 'en_US:en', 16:41:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:41:23 INFO - 'LOGNAME': 'cltbld', 16:41:23 INFO - 'MAIL': '/var/mail/cltbld', 16:41:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:41:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:41:23 INFO - 'MOZ_CRASHREPORTER': '1', 16:41:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:41:23 INFO - 'MOZ_NO_REMOTE': '1', 16:41:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:41:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:41:23 INFO - 'NO_EM_RESTART': '1', 16:41:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:41:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:23 INFO - 'PWD': '/builds/slave/test', 16:41:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:41:23 INFO - 'RUST_BACKTRACE': 'full', 16:41:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:41:23 INFO - 'SHELL': '/bin/bash', 16:41:23 INFO - 'SHLVL': '1', 16:41:23 INFO - 'TERM': 'linux', 16:41:23 INFO - 'TMOUT': '86400', 16:41:23 INFO - 'USER': 'cltbld', 16:41:23 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:41:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:23 INFO - '_': '/tools/buildbot/bin/python'} 16:41:23 INFO - TEST-INFO | started process 7387 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7_oGKF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:41:24 INFO - PID 7387 | 16:41:24 INFO - PID 7387 | (/builds/slave/test/build/application/firefox/firefox:7439): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:41:24 INFO - PID 7387 | 16:41:35 INFO - PID 7387 | RSS: Main: 172412928 16:41:35 INFO - PID 7387 | 16:44:31 INFO - PID 7387 | Cycle 1(1): loaded http://localhost:57142/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57142/tests/dromaeo/cssquery-ext.html) 16:44:33 INFO - PID 7387 | RSS: Main: 175116288 16:44:33 INFO - PID 7387 | 16:47:34 INFO - PID 7387 | Cycle 1(1): loaded http://localhost:57142/tests/dromaeo/cssquery-ext.html (next: http://localhost:57142/tests/dromaeo/cssquery-jquery.html) 16:47:35 INFO - PID 7387 | RSS: Main: 171843584 16:47:35 INFO - PID 7387 | 16:50:36 INFO - PID 7387 | Cycle 1(1): loaded http://localhost:57142/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57142/tests/dromaeo/cssquery-mootools.html) 16:50:37 INFO - PID 7387 | RSS: Main: 173826048 16:50:37 INFO - PID 7387 | 16:53:39 INFO - PID 7387 | Cycle 1(1): loaded http://localhost:57142/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57142/tests/dromaeo/cssquery-prototype.html) 16:53:39 INFO - PID 7387 | RSS: Main: 172748800 16:53:39 INFO - PID 7387 | 16:56:43 INFO - PID 7387 | Cycle 1(1): loaded http://localhost:57142/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57142/tests/dromaeo/cssquery-yui.html) 16:56:44 INFO - PID 7387 | RSS: Main: 173215744 16:56:44 INFO - PID 7387 | 16:59:45 INFO - PID 7387 | Cycle 1(1): loaded http://localhost:57142/tests/dromaeo/cssquery-yui.html (next: http://localhost:57142/tests/dromaeo/cssquery-dojo.html) 16:59:46 INFO - PID 7387 | RSS: Main: 176680960 16:59:46 INFO - PID 7387 | 16:59:46 INFO - PID 7387 | __start_tp_report 16:59:46 INFO - PID 7387 | _x_x_mozilla_page_load 16:59:46 INFO - PID 7387 | _x_x_mozilla_page_load_details 16:59:46 INFO - PID 7387 | |i|pagename|runs| 16:59:46 INFO - PID 7387 | |0;dojo.html;3275.7051865332114;3297.917552061199;3320.900372988808;3322.368749937512;3363.865445382183;6464.4505217056485;6479.64361960092;6485.1245081913985;6506.577072490285;6548.639824809639;6292.748290068404;6353.968230158843;6356.396142366468;6363.904541431871;6386.744530218785;5873.236479257701;5925.970370148143;5965.88068238635;5986.970065149668;6043.305019922721;1285.1895806638306;5901.704914754245;5934.703264836762;5941.346451890288;5961.493273071773;5831.300243970708;5838.095095260242;5861.6189947653265;5877.970610146965;5932.644041357511;5831.300243970708;5842.776729886657;5916.674582897939;5918.260217472816;5918.408159184047;5811.535077193815;5821.3977309948605;5889.028310328834;5909.586328956974;5915.290165180163;6242.781502647385;6320.93679063208;6448.806535803933;6453.548251622388;6460.741570337181;6673.399394054559;6746.359095885883;6833.794986150423;6888.517803753739;6939.548929319578;6869.656517174121;6872.278410766898;6883.380495755356;6898.724051037952;6905.930940690579;6272.341404152544;6361;6366.554341196118;6371;6372.044193370985;7026.718931242795;7028.718851245944;7049.224585295613;7151.998720179179;7156.499045066899;6690.765823196165;6712.228093769181;6820.352066553769;6825.69284382204;6891.241963384024;6892.965535172392;6897.586144831326;6986.860262794716;6995.685194166274;7004.4046256068805;6729.39435450802;6755.831104222336;6825.249222585511;6864.897026544606;6944.4444444444325;6745;6794.592324460548;6823.761168359084;6836.248012718597;6843.794686159423;5435.646682965595;5456.399796022434;5491.615586908878;5512.39363669996;5514.28314319144;4738.95261047392;4776.8566942991765;4793.880152996203;4800.687955282895;4800.855974320776;5911.467967882912;5993.460588547052;6042.848928776816;6129.35641757609;6131.14164017038;6780.491463140284;6881.862362752717;6900.447964162855;6903.896441553381;6904.4821638377325;6043.546733994968;6095.238095238095;6102.572819902653;6125.816225513153;6128.050152226412;5988.790392336247;6023.216981792339;6030.366811484819;6033.7586496540525;6037.879242415215;6685.130932978682;6713.664316784141;6717.798466046026;6827.795166144924;6829.419499342513;6779.322067793181;6913.965430172817;6946.826329341706;7028.894566581505;7038.507304488841;6583;6592.274849766426;6604.669766511752;6608.636524991171;6621.172353455818;6757;6775.491838112062;6812.4890633203695;6819.318068193141;6834.316568343126;6937.722491100501;6999;7001.229864714979;7005.05431766705;7065.470089743392;6521.087047813215;6732.899006514808;6737.258901520927;6830.487713421414;6840.41856442208;6622.569532980534;6642.734290628514;6648.800535983928;6651.235107962646;6654.434373078343;6595.076689263411;6603.636799976048;6672.933270667231;6685.899711504234;6688.83277918056;6784.6946887389195;6798.116244888133;6807.9319206807295;6821.761238356634;6824.965875170592;6551.344865513411;6568.80293591193;6632.137822083098;6634.9004764927595;6637.535372523877 16:59:46 INFO - PID 7387 | |1;ext.html;746.395419710035;794.6503538443088;807.3541167066347;837.0666706622118;839.127307600096;38550;39554.20891582167;39699.2060158797;39798.20403591926;39900.40149397762;36034;36315;36325.09187270313;36346.45480317797;36352;6769.356911093439;6897.068895699066;6898.241193468714;7578.242175782433;7750.883736743954;7587.279208475214;7686.65410056546;7690.346320562731;7691.461597688164;7692.3076923076915;7646.502977306457;7674.693012279502;7680.5391676499585;7687.500312479671;7700.4609677322605;7610.238976102401;7628.504147230467;7632.885506717404;7650.08199016116;7666.7316643917475;7587.4688771785995;7628.19903910087;7643.847123057508;7651.387888968869;7656.042994625672;37186.25627487462;37410.62589374099;37505.24989500222;37507.24985500302;37513.24973500515;3510.912227194315;3530.947035794465;3534.2578058607724;3549.2546565221332;3555.306715190539;30052.54921176184;30222.69777302221;30314.84842575795;30318.09045728632;30350.84824575863;4880.926786098212;5135.8202462914;5145.845624631267;5201.531862132389;5220.738963051832;189592;193518;194839;207207;208659;46364;46830.29754553684;47027;47133;47256;49197.754011229714;49212.26181607279;50441.24338134931;51331.486685133415;51388.22917656238;14359.92820035914;14420.639484012983;14431.061980971414;14437.711245775236;14501;11362.886371136348;11557.190996630155;11568.652940411801;11591.478383472764;11656.650300491;13100.410481528354;13255.469781208836;13336.866631333562;13352.065355425028;13407.798883016763;5168.198929165986;5225.634205605647;5242.659227150223;5252.579793616578;5284.837335786121;1010.6412223660642;1022.7392015036266;1030.3611760708409;1036.4921188617523;1051.5846240734868;22841.20055798039;23285;23411.297661070195;23419.7658023421;23539.646905296435;1118.5246270335076;1120.2550304047718;1126.8478755368085;1127.2729089737074;1135.823947288155;12263.877361226274;12268.202566833128;12342.506299748089;12429.37853107341;12431.564895228623;233769;233926;235309;235325;235495;50908.236376454384;50934;51106.23340649893;51148;51273.48726512762;1421.1544131241894;1431.7637589797566;1438.8273407190995;1443.5813614051808;1445.5085271007908;22094.779052209917;22256;22286.88856555707;22379.664305035112;22482.32553023412;36102;36120.096997575274;36178.81910590536;36291;36403.27193456169;186032;186116;186366;187269;188261;32634;32718.50922236121;32790.50814237836;32874.68501259923;32887.671123289416;39704.60295397009;40547.391789122594;40900.59099409087;41033.794831025654;41346.1730765377;30431.239219019702;30448.84775576108;30506.694933051273;30592.84703576468;30950.845245773628;30762.230944226572;30935.226619334695;31002.379952400373;31065.689343106278;31178;33752.156196094315;34106.82946585251;34154.65845341515;34205.14487137742;34456.82771586126;30023;30155.698443015288;30685;30787;32934.505982409806;23349.41626459352;23749.64375534334;23827.523449530567;24025.75974240305;24238.636420453353 16:59:46 INFO - PID 7387 | |2;jquery.html;566.4250785452766;577.9913301300479;581.7818318130702;586.73010415209;588.6968211371144;21854.235101771443;22476.213332533363;22523.98642061109;22553.323400297977;22721.659175112385;24030.399240019007;25776.35559111023;25797.226083217487;25810.612840807356;25838.87080564599;6435.099086127934;6564.212294524664;6573.868522629545;6579.17760279965;6580.14458120446;6211.440970312671;6407.967960160193;6442.355764423567;6464.3212462691445;6470.191226096738;6423.775167869126;6432.77485288015;6435.259945106326;6438.066480360345;6443;6438.323975982548;6438.903416448734;6439.420452159305;6442.613443193423;6453.645049522298;5983.940160598426;6000.039993601003;6001.579889407743;6007.279126504804;6016.308124565688;28688.426231475252;29236.41527169466;29730.25674358137;29959.850200748857;30097.849510752527;2089.822365098953;2144.431725592719;2146.291723731165;2146.549224662823;2147.3557932620306;19110.617787644307;19594.902025489922;19657.41027769169;19682.409527714193;19692.803071969385;2387.939183866384;2392.5812982727953;2454.9509009819703;2458.9262322130367;2462.261321603512;133714;140140;140262.2986885069;140824;140856.29571852076;14371.353289102015;15010.924945375204;15032.549023529311;15292.770608440882;15362.231888405759;23072.88463557705;23118.42203944915;23141.768582314297;23174.304770856903;23213.883930580578;9162.312826538036;9336.673216437392;9376.437413755197;9449.574769135406;9475.810483790285;7652.693892244248;8265.504069755834;8308.169183081643;8313.168683131638;8393.202645748644;7980.561069141137;8986.550672466481;9185.265178785681;9313.580888860017;9372.109649583279;2645;2667.5065112954167;2670.8531030793492;2705.337192387838;2806.4106537626917;977.2963466304392;1007.2697294461457;1008.349614498659;1016.5222345497605;1020.0513522424217;19405.2237910482;19528;19741.605167896563;24177;24803.62794558083;1029.0961720816429;1048.7011201807447;1049.5801679328176;1055.5724931402794;1061.9362838229576;11087.168462365355;11301.547938082549;11403.714907127222;11405.315681059163;11441.427928603536;487301;488143;490642;492749;495423;120110.3994480022;121015;125368.37315813363;125650;126497.36751316185;1175.3829239649253;1217.0020583121932;1231.6243545718462;1233.5744168262115;1239.7210627608715;20972.47568810792;21121.26075587347;21175.682364764234;21384.96127904072;21496.570068598226;58746.41253587409;60878;61085;61092.69453652703;62802.37197627965;76005;76131.61934190293;76337;76394;76395.61802190954;11948.283103013848;12368.1960672556;12430.316332601793;14210.07634503754;14380.784288235895;22039;23139.537209255384;23199;23333.300000999996;23587.41031474227;18524.629507410045;18786.71819922675;18810.059497025366;18858.24567017304;18881.622367552845;15059;15070.773938390714;15634.29645665925;16086.276117574971;16383.754243686593;18344.908275458536;18628.906855465637;18712;18746.343877964206;19117;13814.516491922734;14031.859681403464;14044.929775351058;14069.366878490287;14081.507147249797;18149;18207.817921820613;18495.53761155982;18509;18549.258029678662 16:59:46 INFO - PID 7387 | |3;mootools.html;1333.53992872459;1387.4103506009944;1397.6296281485404;1407.6410515318591;1408.3451195194232;14790.926045369772;15472.226388680563;15480.767788483181;15491.535253942371;15562.533124006299;15229.4669686561;16248.350065997345;16295.918520407382;16300.103494307808;16305.266263018162;2651.801114916384;2714.660667416573;2715.7420045095682;2718.9592156117674;2723.1149876290283;2595.3122422558035;2666.306760242341;2734.0704160585396;2736.165469531792;2740.6026126211787;2726.82275652082;2752.1055656911485;2764.8064635475525;2765.0322387164533;2769.8753556090023;2560.1295559509763;2666.813323067376;2714.538528450168;2717.5108480473505;2722.9727702723017;2605.2705242531933;2632.8157029007975;2633.631291619175;2637.1956553251252;2637.5384307746067;15104.093754374662;15385.84606154543;15392;15397.538073857802;15443.845561544353;7977.4016948728495;8249.628766705513;8254.298384637254;8256.711015114503;8261.13258107896;5256.316678831728;5432.348118225798;5473.644213126134;5605.794754127882;5606.55147588192;1948.4836518322657;1973.6743437332823;1993.4318719165112;2032.3902829151195;2043.8569300148995;26823.865880670666;26860.46279074427;27062.188134356;27081.593776093374;27193.864030679917;6427.196600424947;6435.292117867031;6594.571352862049;6642.136522252076;6783.219929708048;17032.48902532901;17045.7443138353;17049.659006819795;17139.22873470697;17174.141292935303;7610.429217808686;7876.921230787619;7879.054513458422;7902.565358905315;7908;7095.9290407096305;7192.496525243288;7265.963670181721;7275.963620181865;7315.963420182865;5129.435762066169;5251.186066159751;5264.263003179558;5290.391604965402;5295.046891559557;1325.3969443903002;1350.837899452072;1414.6321956291233;1422.50923431416;1466.2522113721866;890.7773056735816;906.619219927632;909.7088931541975;918.2975024106564;924.0066928052237;7397.445191610651;7401.222871598512;7445.516041457288;7546.132194797558;7589.962050189824;1008.1581879130861;1009.4548943570389;1014.2089170447063;1023.1967905194387;1025.2310766924807;4407.977960110243;5894.204282421818;6051.546134039964;6084.574079814459;6174.135621013062;21861.234856779938;22554.548909021727;22592.66111008336;22784.430389240402;22822.087116515486;21893.562128757654;22031.33905982823;22330.218442354766;22411.88794056019;22451.55096898053;11068.501917413736;11470.139739519402;11537.711557210969;11681.88318116825;11685.240459370114;9064.410813297114;9076.09239076087;9150.49672268005;9207.86188207164;9216.032316606928;19001.524961876064;19004.334848280243;19127.61744765069;19768.308109216683;19888.3039093631;19258;19697.704534432283;19722.408327750192;19755.308564200757;19812.801871981672;12347.938260308641;12565.811512827499;12771.61685149447;12809.103362764517;12816.6795830105;12390.132690711564;14205.928970355082;14548.272586370427;14559.636009099857;14578.489752858599;9753.414795112316;11046.723831904266;11562.306261623993;11685.591004314809;11924.224925379822;10945.781084378426;10991.120710342993;11054.723631909266;11123.22137450403;11163.776724465628;9810.852837207305;10014.449205293487;10024.849627255742;10029.849552256868;10030.448325342177;7294.161171465349;7460.0674915635545;7588.582627955516;7633.084029916446;7744.303012728881;7739.458237923516;7755.883661744965;7821.804454888674;7869.842603148019;7974.681012759424 16:59:46 INFO - PID 7387 | |4;prototype.html;502.7260143221944;510.1837060702875;531.3119510234246;535.4431391352994;536.5707434052757;6448.35516448355;6470.22357317122;6502.5773324733855;6540.215174179105;6552.967235163829;6106.267247930244;6214.440700336969;6257.968710156454;6343.429091381786;6357;5080.77136528857;5111.08000559899;5121.743912804354;5122;5150.3047088642925;4923.433805112404;5054;5060.165072762989;5061.519155680223;5071.797128114871;5050.368703912011;5063.113955057868;5069.656541016633;5072.34059572257;5121.155009423441;5043.949560504385;5047.637137972745;5058.317127187819;5088.745562721886;5092.719900405476;4546.8863278417975;4566.566176213254;4577.359169716243;4587.67393639612;4591.449026116888;6311.147995020659;6321.4942804575985;6358.554901156921;6361.713722882435;6366.745330186834;5737.942620573825;5813.215215945877;5839.678817665027;5859.355470898197;5862.325832529228;6598.307177746316;6670.3329667033395;6673.366030227121;6697.732090716366;6707;4941.332920055783;4959.313833296696;4960.627952903546;4982.078315511642;4985.825496107655;7045;7069.469789765788;7094.467914906402;7097;7223.963880180566;4938.678985865908;4951.876203094951;4988.725620090857;5022.623303252198;5031.295618613397;5054.721990290569;5056.494350564914;5067.670601410897;5073.909109541466;5110.821121260738;5640.85897852557;5734.39788822176;5738.196652468658;5774.595778295563;5785.681787501728;6611.768588099393;6635.07582800992;6640.634765087966;6665.533412661067;6804.727810887608;1653.2890856931635;1719.0459295091093;1720.6386658801791;1755.2978808476457;1782.2336395349864;1199.8500187476566;1229.514341834988;1237.7957636989977;1241.6833707404735;1242.5092088624942;5802.361740208574;5839.620424672383;5902.1441890926435;5960.582759206901;6020.458158765645;6648.933510664831;6683.930571108599;6717;6812.14848143982;6915.263044814318;5920.733566989496;5961.970190149108;5970.268622099543;6008.819735407945;6024.789132380346;3594.4069228577514;3627.6553727395853;3651.0649355841447;3680.4479328100997;3715.405535114369;6688.398044176048;6721.395074443323;6744;6752.22349429812;6779.9322006780285;6992;6998.755043573552;7002.334778196063;7083.15002199729;7160.964195178991;6628.403443690091;6649.107638177408;6650.56771309873;6725.730970761115;6759.459243260626;6851.588904665736;6861.039454476477;6917.273686263071;6925.342092501204;7000.684969176501;6882.965585172042;6892;6920.377166055079;6931.202911665223;6933.2026816916705;6618.437432818265;6667.699953502201;6673.165854268216;6760.290169532128;6791.626460544718;5026.949730502648;5041.470645582308;5041.546260836542;5045.646804723634;5061.721605311744;6871.656417179221;6894.276101009322;6918.238993710587;6933.549319294431;6938.2940653146325;6575.290424489572;6646.2356828965285;6696.263411024686;6703;6735.6968936397;6515.837104072247;6734.4275736560985;6734.8653026940165;6760.459163266829;6792.490563207681;6455.935440645533;6575.27671956094;6607.405333519814;6651.667416629246;6655.800325990228;6551.475881929529;6636.303188165366;6640.83397915056;6643.269240383457;6736.629485378156;6326.6836658165985;6407.263164736115;6425.453836423956;6461.030845373231;6484.1246431733 16:59:46 INFO - PID 7387 | |5;yui.html;898.505821798011;899.8740176375309;920.0937076979178;929.3169520402503;935.3218916285691;19126.42620721379;20106.89946550267;20108.497287567818;20115.798842011594;21856.89071554644;17457.91271043643;17950.281988720468;21666.566668666615;21737.891310543466;21835.890820545916;986.3440811860105;1001.6344034427418;1003.9046290602417;1004.8442491413842;1008.2438171371472;960.9183219426358;1014.9391036537794;1015.2690063154554;1016.5933626549403;1017.7201269650868;1052.2055847834874;1060.7931453366596;1060.9893901060968;1061.0026575019497;1061.7186445591924;1050.217587897018;1051.7055224537103;1053.2890299048188;1054.46222426563;1059.3802625464114;778.2178910195274;778.3695206882443;778.5212094561804;778.6729573579111;778.7040924448673;19891.104900279523;20151.89924050385;20152.798472015238;20350.796492035188;20352.69470957937;18919.905400473046;19237.615247695107;19311.324103656305;19334.32329868462;19385.9030704847;17028;17392.47822565325;17521.211545480357;17526.123693815258;17759.378421755307;3794.0135564753364;3824.426336049587;3826.2921359548295;3827.2728181645675;3827.425886117077;133714;135940.32029839887;139387;139759;140039.29980350033;16969;17051.658966820592;17172.31310747581;17334.739978900325;17478.126093695264;30663.540046899314;31424;31596.210094747814;31870;31903.840480797448;8348;8382.245597896219;8439;8440.831183376298;8853.64585416589;4154.729942553724;4206.768627725504;4214.0518383364115;4231.196072746168;4242.469691288589;5252.2384254282915;5326.707031113326;5352.223927530564;5360.3299587551555;5363.30277063987;1034.5034383495963;1041.3023274406166;1060.204846365226;1065.3927261460892;1066.1896958311738;705.7459314646723;712.7362875736011;718.270954980695;731.1116992853708;743.8772602520631;12317.938410307892;12390.75218495625;12699.365031748375;13246.735065298833;13361.799573006412;703.919049309336;712.8289210589421;714.7641278378122;722.3138018882083;733.8568979049035;5477.287952566141;5504;5554.888902221933;5678.091505359205;5724.341700704473;104617.38456538496;107778;108222;108559;114577.42711286391;74264.62867685627;94660;96348;98721.50639246758;101154;82824.17175828286;83252.58373708093;84093;85178;86867;11743.706407339885;11924.463399146885;12037.458314375699;12113.515459381526;12242.571509997108;40610.59389406068;40907;41100.588994109676;41132.58867411288;41382.79308603438;67948;70308.29691703222;70732.29267707258;72516;73084.63457682678;19039.619207616048;19653.213871444983;19677.901610491856;19695.901520492305;20043.899780501004;20324.390268291976;20564.177432902517;20570;21297.36107916765;21951.121955122257;18432.07839608041;18835.434936951915;19048.714269285694;19614.705779413034;19942.50143746418;17293;17789.110544472984;18023.549411264823;18089.638207236047;18547.629047419246;17904.641907162044;18124.72812907834;18135;18195.09024548741;18266.634667306313;13540.187588744708;13589.252591107188;13601.86398136006;13996.37016334247;14201.928990354982;13343.399547020214;13978.510752123628;14133.222672753098;14234.644133896321;14834.10995340283 16:59:46 INFO - PID 7387 | __end_tp_report 16:59:46 INFO - PID 7387 | __start_cc_report 16:59:46 INFO - PID 7387 | _x_x_mozilla_cycle_collect,365 16:59:46 INFO - PID 7387 | __end_cc_report 16:59:46 INFO - PID 7387 | __startTimestamp1504742386030__endTimestamp 16:59:46 INFO - PID 7387 | 16:59:46 INFO - PID 7387 | ------- Summary: start ------- 16:59:46 INFO - PID 7387 | Number of tests: 6 16:59:46 INFO - PID 7387 | 16:59:46 INFO - PID 7387 | [#0] dojo.html Cycles:165 Average:6317.75 Median:6604.67 stddev:836.88 (12.7%) stddev-sans-first:804.70 16:59:46 INFO - PID 7387 | Values: 3275.7 3297.9 3320.9 3322.4 3363.9 6464.5 6479.6 6485.1 6506.6 6548.6 6292.7 6354.0 6356.4 6363.9 6386.7 5873.2 5926.0 5965.9 5987.0 6043.3 1285.2 5901.7 5934.7 5941.3 5961.5 5831.3 5838.1 5861.6 5878.0 5932.6 5831.3 5842.8 5916.7 5918.3 5918.4 5811.5 5821.4 5889.0 5909.6 5915.3 6242.8 6320.9 6448.8 6453.5 6460.7 6673.4 6746.4 6833.8 6888.5 6939.5 6869.7 6872.3 6883.4 6898.7 6905.9 6272.3 6361.0 6366.6 6371.0 6372.0 7026.7 7028.7 7049.2 7152.0 7156.5 6690.8 6712.2 6820.4 6825.7 6891.2 6893.0 6897.6 6986.9 6995.7 7004.4 6729.4 6755.8 6825.2 6864.9 6944.4 6745.0 6794.6 6823.8 6836.2 6843.8 5435.6 5456.4 5491.6 5512.4 5514.3 4739.0 4776.9 4793.9 4800.7 4800.9 5911.5 5993.5 6042.8 6129.4 6131.1 6780.5 6881.9 6900.4 6903.9 6904.5 6043.5 6095.2 6102.6 6125.8 6128.1 5988.8 6023.2 6030.4 6033.8 6037.9 6685.1 6713.7 6717.8 6827.8 6829.4 6779.3 6914.0 6946.8 7028.9 7038.5 6583.0 6592.3 6604.7 6608.6 6621.2 6757.0 6775.5 6812.5 6819.3 6834.3 6937.7 6999.0 7001.2 7005.1 7065.5 6521.1 6732.9 6737.3 6830.5 6840.4 6622.6 6642.7 6648.8 6651.2 6654.4 6595.1 6603.6 6672.9 6685.9 6688.8 6784.7 6798.1 6807.9 6821.8 6825.0 6551.3 6568.8 6632.1 6634.9 6637.5 16:59:46 INFO - PID 7387 | 16:59:46 INFO - PID 7387 | [#1] ext.html Cycles:180 Average:36821.30 Median:33929.49 stddev:53899.23 (158.9%) stddev-sans-first:53982.36 16:59:46 INFO - PID 7387 | Values: 746.4 794.7 807.4 837.1 839.1 38550.0 39554.2 39699.2 39798.2 39900.4 36034.0 36315.0 36325.1 36346.5 36352.0 6769.4 6897.1 6898.2 7578.2 7750.9 7587.3 7686.7 7690.3 7691.5 7692.3 7646.5 7674.7 7680.5 7687.5 7700.5 7610.2 7628.5 7632.9 7650.1 7666.7 7587.5 7628.2 7643.8 7651.4 7656.0 37186.3 37410.6 37505.2 37507.2 37513.2 3510.9 3530.9 3534.3 3549.3 3555.3 30052.5 30222.7 30314.8 30318.1 30350.8 4880.9 5135.8 5145.8 5201.5 5220.7 189592.0 193518.0 194839.0 207207.0 208659.0 46364.0 46830.3 47027.0 47133.0 47256.0 49197.8 49212.3 50441.2 51331.5 51388.2 14359.9 14420.6 14431.1 14437.7 14501.0 11362.9 11557.2 11568.7 11591.5 11656.7 13100.4 13255.5 13336.9 13352.1 13407.8 5168.2 5225.6 5242.7 5252.6 5284.8 1010.6 1022.7 1030.4 1036.5 1051.6 22841.2 23285.0 23411.3 23419.8 23539.6 1118.5 1120.3 1126.8 1127.3 1135.8 12263.9 12268.2 12342.5 12429.4 12431.6 233769.0 233926.0 235309.0 235325.0 235495.0 50908.2 50934.0 51106.2 51148.0 51273.5 1421.2 1431.8 1438.8 1443.6 1445.5 22094.8 22256.0 22286.9 22379.7 22482.3 36102.0 36120.1 36178.8 36291.0 36403.3 186032.0 186116.0 186366.0 187269.0 188261.0 32634.0 32718.5 32790.5 32874.7 32887.7 39704.6 40547.4 40900.6 41033.8 41346.2 30431.2 30448.8 30506.7 30592.8 30950.8 30762.2 30935.2 31002.4 31065.7 31178.0 33752.2 34106.8 34154.7 34205.1 34456.8 30023.0 30155.7 30685.0 30787.0 32934.5 23349.4 23749.6 23827.5 24025.8 24238.6 16:59:46 INFO - PID 7387 | 16:59:46 INFO - PID 7387 | [#2] jquery.html Cycles:180 Average:35312.01 Median:23140.65 stddev:83085.07 (359.0%) stddev-sans-first:83277.19 16:59:46 INFO - PID 7387 | Values: 566.4 578.0 581.8 586.7 588.7 21854.2 22476.2 22524.0 22553.3 22721.7 24030.4 25776.4 25797.2 25810.6 25838.9 6435.1 6564.2 6573.9 6579.2 6580.1 6211.4 6408.0 6442.4 6464.3 6470.2 6423.8 6432.8 6435.3 6438.1 6443.0 6438.3 6438.9 6439.4 6442.6 6453.6 5983.9 6000.0 6001.6 6007.3 6016.3 28688.4 29236.4 29730.3 29959.9 30097.8 2089.8 2144.4 2146.3 2146.5 2147.4 19110.6 19594.9 19657.4 19682.4 19692.8 2387.9 2392.6 2455.0 2458.9 2462.3 133714.0 140140.0 140262.3 140824.0 140856.3 14371.4 15010.9 15032.5 15292.8 15362.2 23072.9 23118.4 23141.8 23174.3 23213.9 9162.3 9336.7 9376.4 9449.6 9475.8 7652.7 8265.5 8308.2 8313.2 8393.2 7980.6 8986.6 9185.3 9313.6 9372.1 2645.0 2667.5 2670.9 2705.3 2806.4 977.3 1007.3 1008.3 1016.5 1020.1 19405.2 19528.0 19741.6 24177.0 24803.6 1029.1 1048.7 1049.6 1055.6 1061.9 11087.2 11301.5 11403.7 11405.3 11441.4 487301.0 488143.0 490642.0 492749.0 495423.0 120110.4 121015.0 125368.4 125650.0 126497.4 1175.4 1217.0 1231.6 1233.6 1239.7 20972.5 21121.3 21175.7 21385.0 21496.6 58746.4 60878.0 61085.0 61092.7 62802.4 76005.0 76131.6 76337.0 76394.0 76395.6 11948.3 12368.2 12430.3 14210.1 14380.8 22039.0 23139.5 23199.0 23333.3 23587.4 18524.6 18786.7 18810.1 18858.2 18881.6 15059.0 15070.8 15634.3 16086.3 16383.8 18344.9 18628.9 18712.0 18746.3 19117.0 13814.5 14031.9 14044.9 14069.4 14081.5 18149.0 18207.8 18495.5 18509.0 18549.3 16:59:46 INFO - PID 7387 | 16:59:46 INFO - PID 7387 | [#3] mootools.html Cycles:180 Average:9542.06 Median:2619.04 stddev:6876.93 (262.6%) stddev-sans-first:6868.56 16:59:46 INFO - PID 7387 | Values: 1333.5 1387.4 1397.6 1407.6 1408.3 14790.9 15472.2 15480.8 15491.5 15562.5 15229.5 16248.4 16295.9 16300.1 16305.3 2651.8 2714.7 2715.7 2719.0 2723.1 2595.3 2666.3 2734.1 2736.2 2740.6 2726.8 2752.1 2764.8 2765.0 2769.9 2560.1 2666.8 2714.5 2717.5 2723.0 2605.3 2632.8 2633.6 2637.2 2637.5 15104.1 15385.8 15392.0 15397.5 15443.8 7977.4 8249.6 8254.3 8256.7 8261.1 5256.3 5432.3 5473.6 5605.8 5606.6 1948.5 1973.7 1993.4 2032.4 2043.9 26823.9 26860.5 27062.2 27081.6 27193.9 6427.2 6435.3 6594.6 6642.1 6783.2 17032.5 17045.7 17049.7 17139.2 17174.1 7610.4 7876.9 7879.1 7902.6 7908.0 7095.9 7192.5 7266.0 7276.0 7316.0 5129.4 5251.2 5264.3 5290.4 5295.0 1325.4 1350.8 1414.6 1422.5 1466.3 890.8 906.6 909.7 918.3 924.0 7397.4 7401.2 7445.5 7546.1 7590.0 1008.2 1009.5 1014.2 1023.2 1025.2 4408.0 5894.2 6051.5 6084.6 6174.1 21861.2 22554.5 22592.7 22784.4 22822.1 21893.6 22031.3 22330.2 22411.9 22451.6 11068.5 11470.1 11537.7 11681.9 11685.2 9064.4 9076.1 9150.5 9207.9 9216.0 19001.5 19004.3 19127.6 19768.3 19888.3 19258.0 19697.7 19722.4 19755.3 19812.8 12347.9 12565.8 12771.6 12809.1 12816.7 12390.1 14205.9 14548.3 14559.6 14578.5 9753.4 11046.7 11562.3 11685.6 11924.2 10945.8 10991.1 11054.7 11123.2 11163.8 9810.9 10014.4 10024.8 10029.8 10030.4 7294.2 7460.1 7588.6 7633.1 7744.3 7739.5 7755.9 7821.8 7869.8 7974.7 16:59:46 INFO - PID 7387 | 16:59:46 INFO - PID 7387 | [#4] prototype.html Cycles:180 Average:5631.89 Median:6335.06 stddev:1596.92 (25.2%) stddev-sans-first:1554.30 16:59:46 INFO - PID 7387 | Values: 502.7 510.2 531.3 535.4 536.6 6448.4 6470.2 6502.6 6540.2 6553.0 6106.3 6214.4 6258.0 6343.4 6357.0 5080.8 5111.1 5121.7 5122.0 5150.3 4923.4 5054.0 5060.2 5061.5 5071.8 5050.4 5063.1 5069.7 5072.3 5121.2 5043.9 5047.6 5058.3 5088.7 5092.7 4546.9 4566.6 4577.4 4587.7 4591.4 6311.1 6321.5 6358.6 6361.7 6366.7 5737.9 5813.2 5839.7 5859.4 5862.3 6598.3 6670.3 6673.4 6697.7 6707.0 4941.3 4959.3 4960.6 4982.1 4985.8 7045.0 7069.5 7094.5 7097.0 7224.0 4938.7 4951.9 4988.7 5022.6 5031.3 5054.7 5056.5 5067.7 5073.9 5110.8 5640.9 5734.4 5738.2 5774.6 5785.7 6611.8 6635.1 6640.6 6665.5 6804.7 1653.3 1719.0 1720.6 1755.3 1782.2 1199.9 1229.5 1237.8 1241.7 1242.5 5802.4 5839.6 5902.1 5960.6 6020.5 6648.9 6683.9 6717.0 6812.1 6915.3 5920.7 5962.0 5970.3 6008.8 6024.8 3594.4 3627.7 3651.1 3680.4 3715.4 6688.4 6721.4 6744.0 6752.2 6779.9 6992.0 6998.8 7002.3 7083.2 7161.0 6628.4 6649.1 6650.6 6725.7 6759.5 6851.6 6861.0 6917.3 6925.3 7000.7 6883.0 6892.0 6920.4 6931.2 6933.2 6618.4 6667.7 6673.2 6760.3 6791.6 5026.9 5041.5 5041.5 5045.6 5061.7 6871.7 6894.3 6918.2 6933.5 6938.3 6575.3 6646.2 6696.3 6703.0 6735.7 6515.8 6734.4 6734.9 6760.5 6792.5 6455.9 6575.3 6607.4 6651.7 6655.8 6551.5 6636.3 6640.8 6643.3 6736.6 6326.7 6407.3 6425.5 6461.0 6484.1 16:59:46 INFO - PID 7387 | 16:59:46 INFO - PID 7387 | [#5] yui.html Cycles:180 Average:24059.76 Median:19916.80 stddev:32786.23 (164.6%) stddev-sans-first:32832.08 16:59:46 INFO - PID 7387 | Values: 898.5 899.9 920.1 929.3 935.3 19126.4 20106.9 20108.5 20115.8 21856.9 17457.9 17950.3 21666.6 21737.9 21835.9 986.3 1001.6 1003.9 1004.8 1008.2 960.9 1014.9 1015.3 1016.6 1017.7 1052.2 1060.8 1061.0 1061.0 1061.7 1050.2 1051.7 1053.3 1054.5 1059.4 778.2 778.4 778.5 778.7 778.7 19891.1 20151.9 20152.8 20350.8 20352.7 18919.9 19237.6 19311.3 19334.3 19385.9 17028.0 17392.5 17521.2 17526.1 17759.4 3794.0 3824.4 3826.3 3827.3 3827.4 133714.0 135940.3 139387.0 139759.0 140039.3 16969.0 17051.7 17172.3 17334.7 17478.1 30663.5 31424.0 31596.2 31870.0 31903.8 8348.0 8382.2 8439.0 8440.8 8853.6 4154.7 4206.8 4214.1 4231.2 4242.5 5252.2 5326.7 5352.2 5360.3 5363.3 1034.5 1041.3 1060.2 1065.4 1066.2 705.7 712.7 718.3 731.1 743.9 12317.9 12390.8 12699.4 13246.7 13361.8 703.9 712.8 714.8 722.3 733.9 5477.3 5504.0 5554.9 5678.1 5724.3 104617.4 107778.0 108222.0 108559.0 114577.4 74264.6 94660.0 96348.0 98721.5 101154.0 82824.2 83252.6 84093.0 85178.0 86867.0 11743.7 11924.5 12037.5 12113.5 12242.6 40610.6 40907.0 41100.6 41132.6 41382.8 67948.0 70308.3 70732.3 72516.0 73084.6 19039.6 19653.2 19677.9 19695.9 20043.9 20324.4 20564.2 20570.0 21297.4 21951.1 18432.1 18835.4 19048.7 19614.7 19942.5 17293.0 17789.1 18023.5 18089.6 18547.6 17904.6 18124.7 18135.0 18195.1 18266.6 13540.2 13589.3 13601.9 13996.4 14201.9 13343.4 13978.5 14133.2 14234.6 14834.1 16:59:46 INFO - PID 7387 | -------- Summary: end -------- 16:59:46 INFO - PID 7387 | 16:59:46 INFO - TEST-INFO | 7387: exit 0 16:59:46 INFO - TEST-OK | dromaeo_css | took 1108647ms 16:59:46 INFO - TEST-START | kraken 16:59:46 INFO - Initialising browser for kraken test... 16:59:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57142/getInfo.html -profile /tmp/tmpcOqh_9/profile 16:59:46 INFO - TEST-INFO | started process 7763 (/builds/slave/test/build/application/firefox/firefox http://localhost:57142/getInfo.html) 16:59:52 INFO - TEST-INFO | 7763: exit 0 16:59:52 INFO - Browser initialized. 16:59:52 INFO - Running cycle 1/1 for kraken test... 16:59:52 INFO - Using env: {'DISPLAY': ':0', 16:59:52 INFO - 'HOME': '/home/cltbld', 16:59:52 INFO - 'JSGC_DISABLE_POISONING': '1', 16:59:52 INFO - 'LANG': 'en_US.UTF-8', 16:59:52 INFO - 'LANGUAGE': 'en_US:en', 16:59:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:59:52 INFO - 'LOGNAME': 'cltbld', 16:59:52 INFO - 'MAIL': '/var/mail/cltbld', 16:59:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:59:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:59:52 INFO - 'MOZ_CRASHREPORTER': '1', 16:59:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:59:52 INFO - 'MOZ_NO_REMOTE': '1', 16:59:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:59:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:59:52 INFO - 'NO_EM_RESTART': '1', 16:59:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:59:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:52 INFO - 'PWD': '/builds/slave/test', 16:59:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:59:52 INFO - 'RUST_BACKTRACE': 'full', 16:59:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:59:52 INFO - 'SHELL': '/bin/bash', 16:59:52 INFO - 'SHLVL': '1', 16:59:52 INFO - 'TERM': 'linux', 16:59:52 INFO - 'TMOUT': '86400', 16:59:52 INFO - 'USER': 'cltbld', 16:59:52 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938', 16:59:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:52 INFO - '_': '/tools/buildbot/bin/python'} 16:59:52 INFO - TEST-INFO | started process 7960 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcOqh_9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:59:53 INFO - PID 7960 | 16:59:53 INFO - PID 7960 | (/builds/slave/test/build/application/firefox/firefox:8012): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:59:53 INFO - PID 7960 | 17:00:04 INFO - PID 7960 | RSS: Main: 167153664 17:00:04 INFO - PID 7960 | 17:00:06 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57142/tests/kraken/driver.html?testName=audio-beat-detection) 17:00:07 INFO - PID 7960 | RSS: Main: 172646400 17:00:07 INFO - PID 7960 | 17:00:10 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57142/tests/kraken/driver.html?testName=audio-dft) 17:00:10 INFO - PID 7960 | RSS: Main: 173383680 17:00:10 INFO - PID 7960 | 17:00:13 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57142/tests/kraken/driver.html?testName=audio-fft) 17:00:14 INFO - PID 7960 | RSS: Main: 168026112 17:00:14 INFO - PID 7960 | 17:00:16 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57142/tests/kraken/driver.html?testName=audio-oscillator) 17:00:17 INFO - PID 7960 | RSS: Main: 168820736 17:00:17 INFO - PID 7960 | 17:00:19 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57142/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:00:19 INFO - PID 7960 | RSS: Main: 169156608 17:00:19 INFO - PID 7960 | 17:00:23 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57142/tests/kraken/driver.html?testName=imaging-darkroom) 17:00:23 INFO - PID 7960 | RSS: Main: 171663360 17:00:23 INFO - PID 7960 | 17:00:27 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57142/tests/kraken/driver.html?testName=imaging-desaturate) 17:00:27 INFO - PID 7960 | RSS: Main: 171347968 17:00:27 INFO - PID 7960 | 17:00:30 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57142/tests/kraken/driver.html?testName=json-parse-financial) 17:00:31 INFO - PID 7960 | RSS: Main: 171266048 17:00:31 INFO - PID 7960 | 17:00:33 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57142/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:00:33 INFO - PID 7960 | RSS: Main: 172613632 17:00:33 INFO - PID 7960 | 17:00:35 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57142/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:00:35 INFO - PID 7960 | RSS: Main: 171585536 17:00:35 INFO - PID 7960 | 17:00:37 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57142/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:00:38 INFO - PID 7960 | RSS: Main: 172298240 17:00:38 INFO - PID 7960 | 17:00:41 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57142/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:00:42 INFO - PID 7960 | RSS: Main: 172388352 17:00:42 INFO - PID 7960 | 17:00:45 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57142/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:00:45 INFO - PID 7960 | RSS: Main: 172711936 17:00:45 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | Cycle 1(1): loaded http://localhost:57142/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57142/tests/kraken/driver.html?testName=ai-astar) 17:00:47 INFO - PID 7960 | RSS: Main: 174469120 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | __start_tp_report 17:00:47 INFO - PID 7960 | _x_x_mozilla_page_load 17:00:47 INFO - PID 7960 | _x_x_mozilla_page_load_details 17:00:47 INFO - PID 7960 | |i|pagename|runs| 17:00:47 INFO - PID 7960 | |0;ai-astar;97;106;101;109;107;108;109;99;107;108 17:00:47 INFO - PID 7960 | |1;audio-beat-detection;153;116;151;181;117;141;146;120;173;119 17:00:47 INFO - PID 7960 | |2;audio-dft;162;163;163;162;162;164;171;171;164;161 17:00:47 INFO - PID 7960 | |3;audio-fft;75;74;74;180;74;75;74;74;147;75 17:00:47 INFO - PID 7960 | |4;audio-oscillator;79;78;129;79;79;78;130;79;81;139 17:00:47 INFO - PID 7960 | |5;imaging-gaussian-blur;115;114;114;113;114;114;115;114;114;117 17:00:47 INFO - PID 7960 | |6;imaging-darkroom;149;150;148;149;148;148;151;148;147;148 17:00:47 INFO - PID 7960 | |7;imaging-desaturate;94;94;96;94;95;95;97;95;94;94 17:00:47 INFO - PID 7960 | |8;json-parse-financial;54;55;56;53;55;54;53;54;53;54 17:00:47 INFO - PID 7960 | |9;json-stringify-tinderbox;38;37;37;37;37;38;37;38;38;37 17:00:47 INFO - PID 7960 | |10;stanford-crypto-aes;74;75;74;74;75;74;73;75;73;74 17:00:47 INFO - PID 7960 | |11;stanford-crypto-ccm;118;117;118;126;121;121;131;118;126;173 17:00:47 INFO - PID 7960 | |12;stanford-crypto-pbkdf2;173;168;183;173;173;177;167;176;172;167 17:00:47 INFO - PID 7960 | |13;stanford-crypto-sha256-iterative;66;64;66;65;66;63;63;71;67;63 17:00:47 INFO - PID 7960 | __end_tp_report 17:00:47 INFO - PID 7960 | __start_cc_report 17:00:47 INFO - PID 7960 | _x_x_mozilla_cycle_collect,930 17:00:47 INFO - PID 7960 | __end_cc_report 17:00:47 INFO - PID 7960 | __startTimestamp1504742447693__endTimestamp 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | ------- Summary: start ------- 17:00:47 INFO - PID 7960 | Number of tests: 14 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#0] ai-astar Cycles:10 Average:105.10 Median:108.50 stddev:4.41 (4.1%) stddev-sans-first:3.57 17:00:47 INFO - PID 7960 | Values: 97.0 106.0 101.0 109.0 107.0 108.0 109.0 99.0 107.0 108.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:148.50 stddev:23.60 (15.9%) stddev-sans-first:24.68 17:00:47 INFO - PID 7960 | Values: 153.0 116.0 151.0 181.0 117.0 141.0 146.0 120.0 173.0 119.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#2] audio-dft Cycles:10 Average:164.30 Median:163.50 stddev:3.65 (2.2%) stddev-sans-first:3.78 17:00:47 INFO - PID 7960 | Values: 162.0 163.0 163.0 162.0 162.0 164.0 171.0 171.0 164.0 161.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#3] audio-fft Cycles:10 Average:92.20 Median:74.00 stddev:38.38 (51.9%) stddev-sans-first:40.20 17:00:47 INFO - PID 7960 | Values: 75.0 74.0 74.0 180.0 74.0 75.0 74.0 74.0 147.0 75.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:79.00 stddev:26.07 (33.0%) stddev-sans-first:26.99 17:00:47 INFO - PID 7960 | Values: 79.0 78.0 129.0 79.0 79.0 78.0 130.0 79.0 81.0 139.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:1.07 (0.9%) stddev-sans-first:1.12 17:00:47 INFO - PID 7960 | Values: 115.0 114.0 114.0 113.0 114.0 114.0 115.0 114.0 114.0 117.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#6] imaging-darkroom Cycles:10 Average:148.60 Median:148.50 stddev:1.17 (0.8%) stddev-sans-first:1.24 17:00:47 INFO - PID 7960 | Values: 149.0 150.0 148.0 149.0 148.0 148.0 151.0 148.0 147.0 148.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:1.03 (1.1%) stddev-sans-first:1.05 17:00:47 INFO - PID 7960 | Values: 94.0 94.0 96.0 94.0 95.0 95.0 97.0 95.0 94.0 94.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:1.05 17:00:47 INFO - PID 7960 | Values: 54.0 55.0 56.0 53.0 55.0 54.0 53.0 54.0 53.0 54.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#9] json-stringify-tinderbox Cycles:10 Average:37.40 Median:37.50 stddev:0.52 (1.4%) stddev-sans-first:0.50 17:00:47 INFO - PID 7960 | Values: 38.0 37.0 37.0 37.0 37.0 38.0 37.0 38.0 38.0 37.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:74.00 stddev:0.74 (1.0%) stddev-sans-first:0.78 17:00:47 INFO - PID 7960 | Values: 74.0 75.0 74.0 74.0 75.0 74.0 73.0 75.0 73.0 74.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:123.50 stddev:16.83 (13.6%) stddev-sans-first:17.54 17:00:47 INFO - PID 7960 | Values: 118.0 117.0 118.0 126.0 121.0 121.0 131.0 118.0 126.0 173.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:173.00 stddev:4.98 (2.9%) stddev-sans-first:5.28 17:00:47 INFO - PID 7960 | Values: 173.0 168.0 183.0 173.0 173.0 177.0 167.0 176.0 172.0 167.0 17:00:47 INFO - PID 7960 | 17:00:47 INFO - PID 7960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.00 stddev:2.46 (3.7%) stddev-sans-first:2.60 17:00:47 INFO - PID 7960 | Values: 66.0 64.0 66.0 65.0 66.0 63.0 63.0 71.0 67.0 63.0 17:00:47 INFO - PID 7960 | -------- Summary: end -------- 17:00:47 INFO - PID 7960 | 17:00:48 INFO - PID 7960 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:00:48 INFO - TEST-INFO | 7960: exit 0 17:00:48 INFO - TEST-OK | kraken | took 61718ms 17:00:48 INFO - SUITE-END | took 1170s 17:00:48 INFO - Completed test suite (00:19:30) 17:00:48 INFO - javascript benchmark 17:00:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8607.03668331031, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3275.7051865332114, 3297.917552061199, 3320.900372988808, 3322.368749937512, 3363.865445382183, 6464.4505217056485, 6479.64361960092, 6485.1245081913985, 6506.577072490285, 6548.639824809639, 6292.748290068404, 6353.968230158843, 6356.396142366468, 6363.904541431871, 6386.744530218785, 5873.236479257701, 5925.970370148143, 5965.88068238635, 5986.970065149668, 6043.305019922721, 1285.1895806638306, 5901.704914754245, 5934.703264836762, 5941.346451890288, 5961.493273071773, 5831.300243970708, 5838.095095260242, 5861.6189947653265, 5877.970610146965, 5932.644041357511, 5831.300243970708, 5842.776729886657, 5916.674582897939, 5918.260217472816, 5918.408159184047, 5811.535077193815, 5821.3977309948605, 5889.028310328834, 5909.586328956974, 5915.290165180163, 6242.781502647385, 6320.93679063208, 6448.806535803933, 6453.548251622388, 6460.741570337181, 6673.399394054559, 6746.359095885883, 6833.794986150423, 6888.517803753739, 6939.548929319578, 6869.656517174121, 6872.278410766898, 6883.380495755356, 6898.724051037952, 6905.930940690579, 6272.341404152544, 6361.0, 6366.554341196118, 6371.0, 6372.044193370985, 7026.718931242795, 7028.718851245944, 7049.224585295613, 7151.998720179179, 7156.499045066899, 6690.765823196165, 6712.228093769181, 6820.352066553769, 6825.69284382204, 6891.241963384024, 6892.965535172392, 6897.586144831326, 6986.860262794716, 6995.685194166274, 7004.4046256068805, 6729.39435450802, 6755.831104222336, 6825.249222585511, 6864.897026544606, 6944.4444444444325, 6745.0, 6794.592324460548, 6823.761168359084, 6836.248012718597, 6843.794686159423, 5435.646682965595, 5456.399796022434, 5491.615586908878, 5512.39363669996, 5514.28314319144, 4738.95261047392, 4776.8566942991765, 4793.880152996203, 4800.687955282895, 4800.855974320776, 5911.467967882912, 5993.460588547052, 6042.848928776816, 6129.35641757609, 6131.14164017038, 6780.491463140284, 6881.862362752717, 6900.447964162855, 6903.896441553381, 6904.4821638377325, 6043.546733994968, 6095.238095238095, 6102.572819902653, 6125.816225513153, 6128.050152226412, 5988.790392336247, 6023.216981792339, 6030.366811484819, 6033.7586496540525, 6037.879242415215, 6685.130932978682, 6713.664316784141, 6717.798466046026, 6827.795166144924, 6829.419499342513, 6779.322067793181, 6913.965430172817, 6946.826329341706, 7028.894566581505, 7038.507304488841, 6583.0, 6592.274849766426, 6604.669766511752, 6608.636524991171, 6621.172353455818, 6757.0, 6775.491838112062, 6812.4890633203695, 6819.318068193141, 6834.316568343126, 6937.722491100501, 6999.0, 7001.229864714979, 7005.05431766705, 7065.470089743392, 6521.087047813215, 6732.899006514808, 6737.258901520927, 6830.487713421414, 6840.41856442208, 6622.569532980534, 6642.734290628514, 6648.800535983928, 6651.235107962646, 6654.434373078343, 6595.076689263411, 6603.636799976048, 6672.933270667231, 6685.899711504234, 6688.83277918056, 6784.6946887389195, 6798.116244888133, 6807.9319206807295, 6821.761238356634, 6824.965875170592, 6551.344865513411, 6568.80293591193, 6632.137822083098, 6634.9004764927595, 6637.535372523877], "value": 6259.181035632367, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.395419710035, 794.6503538443088, 807.3541167066347, 837.0666706622118, 839.127307600096, 38550.0, 39554.20891582167, 39699.2060158797, 39798.20403591926, 39900.40149397762, 36034.0, 36315.0, 36325.09187270313, 36346.45480317797, 36352.0, 6769.356911093439, 6897.068895699066, 6898.241193468714, 7578.242175782433, 7750.883736743954, 7587.279208475214, 7686.65410056546, 7690.346320562731, 7691.461597688164, 7692.3076923076915, 7646.502977306457, 7674.693012279502, 7680.5391676499585, 7687.500312479671, 7700.4609677322605, 7610.238976102401, 7628.504147230467, 7632.885506717404, 7650.08199016116, 7666.7316643917475, 7587.4688771785995, 7628.19903910087, 7643.847123057508, 7651.387888968869, 7656.042994625672, 37186.25627487462, 37410.62589374099, 37505.24989500222, 37507.24985500302, 37513.24973500515, 3510.912227194315, 3530.947035794465, 3534.2578058607724, 3549.2546565221332, 3555.306715190539, 30052.54921176184, 30222.69777302221, 30314.84842575795, 30318.09045728632, 30350.84824575863, 4880.926786098212, 5135.8202462914, 5145.845624631267, 5201.531862132389, 5220.738963051832, 189592.0, 193518.0, 194839.0, 207207.0, 208659.0, 46364.0, 46830.29754553684, 47027.0, 47133.0, 47256.0, 49197.754011229714, 49212.26181607279, 50441.24338134931, 51331.486685133415, 51388.22917656238, 14359.92820035914, 14420.639484012983, 14431.061980971414, 14437.711245775236, 14501.0, 11362.886371136348, 11557.190996630155, 11568.652940411801, 11591.478383472764, 11656.650300491, 13100.410481528354, 13255.469781208836, 13336.866631333562, 13352.065355425028, 13407.798883016763, 5168.198929165986, 5225.634205605647, 5242.659227150223, 5252.579793616578, 5284.837335786121, 1010.6412223660642, 1022.7392015036266, 1030.3611760708409, 1036.4921188617523, 1051.5846240734868, 22841.20055798039, 23285.0, 23411.297661070195, 23419.7658023421, 23539.646905296435, 1118.5246270335076, 1120.2550304047718, 1126.8478755368085, 1127.2729089737074, 1135.823947288155, 12263.877361226274, 12268.202566833128, 12342.506299748089, 12429.37853107341, 12431.564895228623, 233769.0, 233926.0, 235309.0, 235325.0, 235495.0, 50908.236376454384, 50934.0, 51106.23340649893, 51148.0, 51273.48726512762, 1421.1544131241894, 1431.7637589797566, 1438.8273407190995, 1443.5813614051808, 1445.5085271007908, 22094.779052209917, 22256.0, 22286.88856555707, 22379.664305035112, 22482.32553023412, 36102.0, 36120.096997575274, 36178.81910590536, 36291.0, 36403.27193456169, 186032.0, 186116.0, 186366.0, 187269.0, 188261.0, 32634.0, 32718.50922236121, 32790.50814237836, 32874.68501259923, 32887.671123289416, 39704.60295397009, 40547.391789122594, 40900.59099409087, 41033.794831025654, 41346.1730765377, 30431.239219019702, 30448.84775576108, 30506.694933051273, 30592.84703576468, 30950.845245773628, 30762.230944226572, 30935.226619334695, 31002.379952400373, 31065.689343106278, 31178.0, 33752.156196094315, 34106.82946585251, 34154.65845341515, 34205.14487137742, 34456.82771586126, 30023.0, 30155.698443015288, 30685.0, 30787.0, 32934.505982409806, 23349.41626459352, 23749.64375534334, 23827.523449530567, 24025.75974240305, 24238.636420453353], "value": 16620.407361808655, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.4250785452766, 577.9913301300479, 581.7818318130702, 586.73010415209, 588.6968211371144, 21854.235101771443, 22476.213332533363, 22523.98642061109, 22553.323400297977, 22721.659175112385, 24030.399240019007, 25776.35559111023, 25797.226083217487, 25810.612840807356, 25838.87080564599, 6435.099086127934, 6564.212294524664, 6573.868522629545, 6579.17760279965, 6580.14458120446, 6211.440970312671, 6407.967960160193, 6442.355764423567, 6464.3212462691445, 6470.191226096738, 6423.775167869126, 6432.77485288015, 6435.259945106326, 6438.066480360345, 6443.0, 6438.323975982548, 6438.903416448734, 6439.420452159305, 6442.613443193423, 6453.645049522298, 5983.940160598426, 6000.039993601003, 6001.579889407743, 6007.279126504804, 6016.308124565688, 28688.426231475252, 29236.41527169466, 29730.25674358137, 29959.850200748857, 30097.849510752527, 2089.822365098953, 2144.431725592719, 2146.291723731165, 2146.549224662823, 2147.3557932620306, 19110.617787644307, 19594.902025489922, 19657.41027769169, 19682.409527714193, 19692.803071969385, 2387.939183866384, 2392.5812982727953, 2454.9509009819703, 2458.9262322130367, 2462.261321603512, 133714.0, 140140.0, 140262.2986885069, 140824.0, 140856.29571852076, 14371.353289102015, 15010.924945375204, 15032.549023529311, 15292.770608440882, 15362.231888405759, 23072.88463557705, 23118.42203944915, 23141.768582314297, 23174.304770856903, 23213.883930580578, 9162.312826538036, 9336.673216437392, 9376.437413755197, 9449.574769135406, 9475.810483790285, 7652.693892244248, 8265.504069755834, 8308.169183081643, 8313.168683131638, 8393.202645748644, 7980.561069141137, 8986.550672466481, 9185.265178785681, 9313.580888860017, 9372.109649583279, 2645.0, 2667.5065112954167, 2670.8531030793492, 2705.337192387838, 2806.4106537626917, 977.2963466304392, 1007.2697294461457, 1008.349614498659, 1016.5222345497605, 1020.0513522424217, 19405.2237910482, 19528.0, 19741.605167896563, 24177.0, 24803.62794558083, 1029.0961720816429, 1048.7011201807447, 1049.5801679328176, 1055.5724931402794, 1061.9362838229576, 11087.168462365355, 11301.547938082549, 11403.714907127222, 11405.315681059163, 11441.427928603536, 487301.0, 488143.0, 490642.0, 492749.0, 495423.0, 120110.3994480022, 121015.0, 125368.37315813363, 125650.0, 126497.36751316185, 1175.3829239649253, 1217.0020583121932, 1231.6243545718462, 1233.5744168262115, 1239.7210627608715, 20972.47568810792, 21121.26075587347, 21175.682364764234, 21384.96127904072, 21496.570068598226, 58746.41253587409, 60878.0, 61085.0, 61092.69453652703, 62802.37197627965, 76005.0, 76131.61934190293, 76337.0, 76394.0, 76395.61802190954, 11948.283103013848, 12368.1960672556, 12430.316332601793, 14210.07634503754, 14380.784288235895, 22039.0, 23139.537209255384, 23199.0, 23333.300000999996, 23587.41031474227, 18524.629507410045, 18786.71819922675, 18810.059497025366, 18858.24567017304, 18881.622367552845, 15059.0, 15070.773938390714, 15634.29645665925, 16086.276117574971, 16383.754243686593, 18344.908275458536, 18628.906855465637, 18712.0, 18746.343877964206, 19117.0, 13814.516491922734, 14031.859681403464, 14044.929775351058, 14069.366878490287, 14081.507147249797, 18149.0, 18207.817921820613, 18495.53761155982, 18509.0, 18549.258029678662], "value": 12017.741674559118, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.53992872459, 1387.4103506009944, 1397.6296281485404, 1407.6410515318591, 1408.3451195194232, 14790.926045369772, 15472.226388680563, 15480.767788483181, 15491.535253942371, 15562.533124006299, 15229.4669686561, 16248.350065997345, 16295.918520407382, 16300.103494307808, 16305.266263018162, 2651.801114916384, 2714.660667416573, 2715.7420045095682, 2718.9592156117674, 2723.1149876290283, 2595.3122422558035, 2666.306760242341, 2734.0704160585396, 2736.165469531792, 2740.6026126211787, 2726.82275652082, 2752.1055656911485, 2764.8064635475525, 2765.0322387164533, 2769.8753556090023, 2560.1295559509763, 2666.813323067376, 2714.538528450168, 2717.5108480473505, 2722.9727702723017, 2605.2705242531933, 2632.8157029007975, 2633.631291619175, 2637.1956553251252, 2637.5384307746067, 15104.093754374662, 15385.84606154543, 15392.0, 15397.538073857802, 15443.845561544353, 7977.4016948728495, 8249.628766705513, 8254.298384637254, 8256.711015114503, 8261.13258107896, 5256.316678831728, 5432.348118225798, 5473.644213126134, 5605.794754127882, 5606.55147588192, 1948.4836518322657, 1973.6743437332823, 1993.4318719165112, 2032.3902829151195, 2043.8569300148995, 26823.865880670666, 26860.46279074427, 27062.188134356, 27081.593776093374, 27193.864030679917, 6427.196600424947, 6435.292117867031, 6594.571352862049, 6642.136522252076, 6783.219929708048, 17032.48902532901, 17045.7443138353, 17049.659006819795, 17139.22873470697, 17174.141292935303, 7610.429217808686, 7876.921230787619, 7879.054513458422, 7902.565358905315, 7908.0, 7095.9290407096305, 7192.496525243288, 7265.963670181721, 7275.963620181865, 7315.963420182865, 5129.435762066169, 5251.186066159751, 5264.263003179558, 5290.391604965402, 5295.046891559557, 1325.3969443903002, 1350.837899452072, 1414.6321956291233, 1422.50923431416, 1466.2522113721866, 890.7773056735816, 906.619219927632, 909.7088931541975, 918.2975024106564, 924.0066928052237, 7397.445191610651, 7401.222871598512, 7445.516041457288, 7546.132194797558, 7589.962050189824, 1008.1581879130861, 1009.4548943570389, 1014.2089170447063, 1023.1967905194387, 1025.2310766924807, 4407.977960110243, 5894.204282421818, 6051.546134039964, 6084.574079814459, 6174.135621013062, 21861.234856779938, 22554.548909021727, 22592.66111008336, 22784.430389240402, 22822.087116515486, 21893.562128757654, 22031.33905982823, 22330.218442354766, 22411.88794056019, 22451.55096898053, 11068.501917413736, 11470.139739519402, 11537.711557210969, 11681.88318116825, 11685.240459370114, 9064.410813297114, 9076.09239076087, 9150.49672268005, 9207.86188207164, 9216.032316606928, 19001.524961876064, 19004.334848280243, 19127.61744765069, 19768.308109216683, 19888.3039093631, 19258.0, 19697.704534432283, 19722.408327750192, 19755.308564200757, 19812.801871981672, 12347.938260308641, 12565.811512827499, 12771.61685149447, 12809.103362764517, 12816.6795830105, 12390.132690711564, 14205.928970355082, 14548.272586370427, 14559.636009099857, 14578.489752858599, 9753.414795112316, 11046.723831904266, 11562.306261623993, 11685.591004314809, 11924.224925379822, 10945.781084378426, 10991.120710342993, 11054.723631909266, 11123.22137450403, 11163.776724465628, 9810.852837207305, 10014.449205293487, 10024.849627255742, 10029.849552256868, 10030.448325342177, 7294.161171465349, 7460.0674915635545, 7588.582627955516, 7633.084029916446, 7744.303012728881, 7739.458237923516, 7755.883661744965, 7821.804454888674, 7869.842603148019, 7974.681012759424], "value": 6785.467850928014, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.7260143221944, 510.1837060702875, 531.3119510234246, 535.4431391352994, 536.5707434052757, 6448.35516448355, 6470.22357317122, 6502.5773324733855, 6540.215174179105, 6552.967235163829, 6106.267247930244, 6214.440700336969, 6257.968710156454, 6343.429091381786, 6357.0, 5080.77136528857, 5111.08000559899, 5121.743912804354, 5122.0, 5150.3047088642925, 4923.433805112404, 5054.0, 5060.165072762989, 5061.519155680223, 5071.797128114871, 5050.368703912011, 5063.113955057868, 5069.656541016633, 5072.34059572257, 5121.155009423441, 5043.949560504385, 5047.637137972745, 5058.317127187819, 5088.745562721886, 5092.719900405476, 4546.8863278417975, 4566.566176213254, 4577.359169716243, 4587.67393639612, 4591.449026116888, 6311.147995020659, 6321.4942804575985, 6358.554901156921, 6361.713722882435, 6366.745330186834, 5737.942620573825, 5813.215215945877, 5839.678817665027, 5859.355470898197, 5862.325832529228, 6598.307177746316, 6670.3329667033395, 6673.366030227121, 6697.732090716366, 6707.0, 4941.332920055783, 4959.313833296696, 4960.627952903546, 4982.078315511642, 4985.825496107655, 7045.0, 7069.469789765788, 7094.467914906402, 7097.0, 7223.963880180566, 4938.678985865908, 4951.876203094951, 4988.725620090857, 5022.623303252198, 5031.295618613397, 5054.721990290569, 5056.494350564914, 5067.670601410897, 5073.909109541466, 5110.821121260738, 5640.85897852557, 5734.39788822176, 5738.196652468658, 5774.595778295563, 5785.681787501728, 6611.768588099393, 6635.07582800992, 6640.634765087966, 6665.533412661067, 6804.727810887608, 1653.2890856931635, 1719.0459295091093, 1720.6386658801791, 1755.2978808476457, 1782.2336395349864, 1199.8500187476566, 1229.514341834988, 1237.7957636989977, 1241.6833707404735, 1242.5092088624942, 5802.361740208574, 5839.620424672383, 5902.1441890926435, 5960.582759206901, 6020.458158765645, 6648.933510664831, 6683.930571108599, 6717.0, 6812.14848143982, 6915.263044814318, 5920.733566989496, 5961.970190149108, 5970.268622099543, 6008.819735407945, 6024.789132380346, 3594.4069228577514, 3627.6553727395853, 3651.0649355841447, 3680.4479328100997, 3715.405535114369, 6688.398044176048, 6721.395074443323, 6744.0, 6752.22349429812, 6779.9322006780285, 6992.0, 6998.755043573552, 7002.334778196063, 7083.15002199729, 7160.964195178991, 6628.403443690091, 6649.107638177408, 6650.56771309873, 6725.730970761115, 6759.459243260626, 6851.588904665736, 6861.039454476477, 6917.273686263071, 6925.342092501204, 7000.684969176501, 6882.965585172042, 6892.0, 6920.377166055079, 6931.202911665223, 6933.2026816916705, 6618.437432818265, 6667.699953502201, 6673.165854268216, 6760.290169532128, 6791.626460544718, 5026.949730502648, 5041.470645582308, 5041.546260836542, 5045.646804723634, 5061.721605311744, 6871.656417179221, 6894.276101009322, 6918.238993710587, 6933.549319294431, 6938.2940653146325, 6575.290424489572, 6646.2356828965285, 6696.263411024686, 6703.0, 6735.6968936397, 6515.837104072247, 6734.4275736560985, 6734.8653026940165, 6760.459163266829, 6792.490563207681, 6455.935440645533, 6575.27671956094, 6607.405333519814, 6651.667416629246, 6655.800325990228, 6551.475881929529, 6636.303188165366, 6640.83397915056, 6643.269240383457, 6736.629485378156, 6326.6836658165985, 6407.263164736115, 6425.453836423956, 6461.030845373231, 6484.1246431733], "value": 5158.582873672528, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [898.505821798011, 899.8740176375309, 920.0937076979178, 929.3169520402503, 935.3218916285691, 19126.42620721379, 20106.89946550267, 20108.497287567818, 20115.798842011594, 21856.89071554644, 17457.91271043643, 17950.281988720468, 21666.566668666615, 21737.891310543466, 21835.890820545916, 986.3440811860105, 1001.6344034427418, 1003.9046290602417, 1004.8442491413842, 1008.2438171371472, 960.9183219426358, 1014.9391036537794, 1015.2690063154554, 1016.5933626549403, 1017.7201269650868, 1052.2055847834874, 1060.7931453366596, 1060.9893901060968, 1061.0026575019497, 1061.7186445591924, 1050.217587897018, 1051.7055224537103, 1053.2890299048188, 1054.46222426563, 1059.3802625464114, 778.2178910195274, 778.3695206882443, 778.5212094561804, 778.6729573579111, 778.7040924448673, 19891.104900279523, 20151.89924050385, 20152.798472015238, 20350.796492035188, 20352.69470957937, 18919.905400473046, 19237.615247695107, 19311.324103656305, 19334.32329868462, 19385.9030704847, 17028.0, 17392.47822565325, 17521.211545480357, 17526.123693815258, 17759.378421755307, 3794.0135564753364, 3824.426336049587, 3826.2921359548295, 3827.2728181645675, 3827.425886117077, 133714.0, 135940.32029839887, 139387.0, 139759.0, 140039.29980350033, 16969.0, 17051.658966820592, 17172.31310747581, 17334.739978900325, 17478.126093695264, 30663.540046899314, 31424.0, 31596.210094747814, 31870.0, 31903.840480797448, 8348.0, 8382.245597896219, 8439.0, 8440.831183376298, 8853.64585416589, 4154.729942553724, 4206.768627725504, 4214.0518383364115, 4231.196072746168, 4242.469691288589, 5252.2384254282915, 5326.707031113326, 5352.223927530564, 5360.3299587551555, 5363.30277063987, 1034.5034383495963, 1041.3023274406166, 1060.204846365226, 1065.3927261460892, 1066.1896958311738, 705.7459314646723, 712.7362875736011, 718.270954980695, 731.1116992853708, 743.8772602520631, 12317.938410307892, 12390.75218495625, 12699.365031748375, 13246.735065298833, 13361.799573006412, 703.919049309336, 712.8289210589421, 714.7641278378122, 722.3138018882083, 733.8568979049035, 5477.287952566141, 5504.0, 5554.888902221933, 5678.091505359205, 5724.341700704473, 104617.38456538496, 107778.0, 108222.0, 108559.0, 114577.42711286391, 74264.62867685627, 94660.0, 96348.0, 98721.50639246758, 101154.0, 82824.17175828286, 83252.58373708093, 84093.0, 85178.0, 86867.0, 11743.706407339885, 11924.463399146885, 12037.458314375699, 12113.515459381526, 12242.571509997108, 40610.59389406068, 40907.0, 41100.588994109676, 41132.58867411288, 41382.79308603438, 67948.0, 70308.29691703222, 70732.29267707258, 72516.0, 73084.63457682678, 19039.619207616048, 19653.213871444983, 19677.901610491856, 19695.901520492305, 20043.899780501004, 20324.390268291976, 20564.177432902517, 20570.0, 21297.36107916765, 21951.121955122257, 18432.07839608041, 18835.434936951915, 19048.714269285694, 19614.705779413034, 19942.50143746418, 17293.0, 17789.110544472984, 18023.549411264823, 18089.638207236047, 18547.629047419246, 17904.641907162044, 18124.72812907834, 18135.0, 18195.09024548741, 18266.634667306313, 13540.187588744708, 13589.252591107188, 13601.86398136006, 13996.37016334247, 14201.928990354982, 13343.399547020214, 13978.510752123628, 14133.222672753098, 14234.644133896321, 14834.10995340283], "value": 9289.788616114423, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1487.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 106.0, 101.0, 109.0, 107.0, 108.0, 109.0, 99.0, 107.0, 108.0], "value": 105.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 116.0, 151.0, 181.0, 117.0, 141.0, 146.0, 120.0, 173.0, 119.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 162.0, 162.0, 164.0, 171.0, 171.0, 164.0, 161.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 180.0, 74.0, 75.0, 74.0, 74.0, 147.0, 75.0], "value": 92.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 129.0, 79.0, 79.0, 78.0, 130.0, 79.0, 81.0, 139.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 113.0, 114.0, 114.0, 115.0, 114.0, 114.0, 117.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 148.0, 149.0, 148.0, 148.0, 151.0, 148.0, 147.0, 148.0], "value": 148.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 95.0, 95.0, 97.0, 95.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 53.0, 55.0, 54.0, 53.0, 54.0, 53.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 37.0, 37.0, 37.0, 38.0, 37.0, 38.0, 38.0, 37.0], "value": 37.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 74.0, 75.0, 74.0, 73.0, 75.0, 73.0, 74.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 118.0, 126.0, 121.0, 121.0, 131.0, 118.0, 126.0, 173.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 168.0, 183.0, 173.0, 173.0, 177.0, 167.0, 176.0, 172.0, 167.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 65.0, 66.0, 63.0, 63.0, 71.0, 67.0, 63.0], "value": 65.4, "unit": "score"}]}]} 17:00:48 INFO - Return code: 0 17:00:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:00:48 INFO - # TBPL SUCCESS # 17:00:48 INFO - Running post-action listener: _package_coverage_data 17:00:48 INFO - Running post-action listener: _resource_record_post_action 17:00:48 INFO - [mozharness: 2017-09-07 00:00:48.969633Z] Finished run-tests step (success) 17:00:48 INFO - Running post-run listener: _resource_record_post_run 17:00:49 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 391999488; Read time: 12; Write time: 202380 17:00:49 INFO - TinderboxPrint: CPU usage
13.1% 17:00:49 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:00:49 INFO - TinderboxPrint: I/O write bytes / time
391,999,488 / 202,380 17:00:49 INFO - TinderboxPrint: CPU idle
8,231.4 (86.9%) 17:00:49 INFO - TinderboxPrint: CPU user
1,192.1 (12.6%) 17:00:49 INFO - TinderboxPrint: Swap in / out
0 / 0 17:00:49 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106516480; Read time: 0; Write time: 60952 17:00:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:00:49 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 0; Write bytes: 284938240; Read time: 0; Write time: 141428 17:00:49 INFO - Running post-run listener: _upload_blobber_files 17:00:49 INFO - Blob upload gear active. 17:00:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:00:49 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 17:00:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:00:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:00:50 INFO - (blobuploader) - INFO - Open directory for files ... 17:00:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:00:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:00:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:00:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:00:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:00:51 INFO - (blobuploader) - INFO - Done attempting. 17:00:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:00:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:00:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:00:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:00:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:00:52 INFO - (blobuploader) - INFO - Done attempting. 17:00:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:00:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:00:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:00:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:00:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:00:52 INFO - (blobuploader) - INFO - Done attempting. 17:00:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:00:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:00:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:00:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:00:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:00:56 INFO - (blobuploader) - INFO - Done attempting. 17:00:56 INFO - (blobuploader) - INFO - Iteration through files over. 17:00:56 INFO - Return code: 0 17:00:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:00:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:00:56 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f28a4cf302062d3cea6bbba5983b3d65debe729cb5cde23b1f7e81ad902d18ec8b2c7d88b970d4819ad2c39a9e284f924c8854ad6f536b65e4ef98a723d0d9ae", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/375473efcf8929546a0e4305ddf8ba4997e18997b5470725664656005c47a9f90a67997d298cef9c221ff002a8b3b5691668e00acc8b26ce17f8f8228ddf59e9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ea95a4da6bf6849cb560ac3f0a22e81d818588c9015d715731287dc98623cac530f21c614306e40ae2034bd544a894b0900a00d5b4df664193e8feee42e509b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0693330ddad428891e4eb1a14a37e065b36e7d8b7ac42f3ccb91b463309d450ad1f71f6a173b61d0beeb167bae46991485571bf254c41f969eb6c9fd4ead80b4"} 17:00:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:00:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:00:56 INFO - Contents: 17:00:56 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f28a4cf302062d3cea6bbba5983b3d65debe729cb5cde23b1f7e81ad902d18ec8b2c7d88b970d4819ad2c39a9e284f924c8854ad6f536b65e4ef98a723d0d9ae", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/375473efcf8929546a0e4305ddf8ba4997e18997b5470725664656005c47a9f90a67997d298cef9c221ff002a8b3b5691668e00acc8b26ce17f8f8228ddf59e9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ea95a4da6bf6849cb560ac3f0a22e81d818588c9015d715731287dc98623cac530f21c614306e40ae2034bd544a894b0900a00d5b4df664193e8feee42e509b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0693330ddad428891e4eb1a14a37e065b36e7d8b7ac42f3ccb91b463309d450ad1f71f6a173b61d0beeb167bae46991485571bf254c41f969eb6c9fd4ead80b4"} 17:00:56 INFO - Running post-run listener: copy_logs_to_upload_dir 17:00:56 INFO - Copying logs to upload dir... 17:00:56 INFO - mkdir: /builds/slave/test/build/upload/logs 17:00:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.882142 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-06 17:00:56.951584) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 17:00:56.954871) ========= 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=00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f28a4cf302062d3cea6bbba5983b3d65debe729cb5cde23b1f7e81ad902d18ec8b2c7d88b970d4819ad2c39a9e284f924c8854ad6f536b65e4ef98a723d0d9ae", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/375473efcf8929546a0e4305ddf8ba4997e18997b5470725664656005c47a9f90a67997d298cef9c221ff002a8b3b5691668e00acc8b26ce17f8f8228ddf59e9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ea95a4da6bf6849cb560ac3f0a22e81d818588c9015d715731287dc98623cac530f21c614306e40ae2034bd544a894b0900a00d5b4df664193e8feee42e509b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0693330ddad428891e4eb1a14a37e065b36e7d8b7ac42f3ccb91b463309d450ad1f71f6a173b61d0beeb167bae46991485571bf254c41f969eb6c9fd4ead80b4"} build_url:https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011347 build_url: 'https://queue.taskcluster.net/v1/task/UKt4CYvxSaGZCXqv81saJw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f28a4cf302062d3cea6bbba5983b3d65debe729cb5cde23b1f7e81ad902d18ec8b2c7d88b970d4819ad2c39a9e284f924c8854ad6f536b65e4ef98a723d0d9ae", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/375473efcf8929546a0e4305ddf8ba4997e18997b5470725664656005c47a9f90a67997d298cef9c221ff002a8b3b5691668e00acc8b26ce17f8f8228ddf59e9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ea95a4da6bf6849cb560ac3f0a22e81d818588c9015d715731287dc98623cac530f21c614306e40ae2034bd544a894b0900a00d5b4df664193e8feee42e509b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0693330ddad428891e4eb1a14a37e065b36e7d8b7ac42f3ccb91b463309d450ad1f71f6a173b61d0beeb167bae46991485571bf254c41f969eb6c9fd4ead80b4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 17:00:56.983053) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 17:00:56.983396) ========= 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=00204705a9daa57ed339a427000001b9-1504741192.492710-1058420938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004568 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 17:00:57.068474) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 17:00:57.068869) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 17:00:57.069143) ========= ========= Total master_lag: 0.20 =========