builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-073 starttime: 1502274004.16 results: success (0) revision: c93fa2271ee761546d469fb1edc68254f2577b5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.163954) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.164475) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.164954) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.189978) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.190353) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-09 03:20:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.2M=0s 2017-08-09 03:20:04 (42.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130007 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.342055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.342426) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014893 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.395397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 27 secs) (at 2017-08-09 03:20:04.395737) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c93fa2271ee761546d469fb1edc68254f2577b5a --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 c93fa2271ee761546d469fb1edc68254f2577b5a --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=9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-09 03:20:05,104 truncating revision to first 12 chars 2017-08-09 03:20:05,104 Setting DEBUG logging. 2017-08-09 03:20:05,104 attempt 1/10 2017-08-09 03:20:05,104 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c93fa2271ee7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-09 03:20:05,648 attempt 1/10 2017-08-09 03:20:06,249 current task status: no status available at this point. state: PENDING 2017-08-09 03:20:06,250 sleeping for 10.00s (attempt 1/10) 2017-08-09 03:20:16,260 attempt 2/10 2017-08-09 03:20:16,812 current task status: no status available at this point. state: PENDING 2017-08-09 03:20:16,813 sleeping for 16.00s (attempt 2/10) 2017-08-09 03:20:32,829 attempt 3/10 2017-08-09 03:20:33,360 current task status: no status available at this point. state: PENDING 2017-08-09 03:20:33,360 sleeping for 23.00s (attempt 3/10) 2017-08-09 03:20:56,383 attempt 4/10 2017-08-09 03:20:56,906 current task status: no status available at this point. state: PENDING 2017-08-09 03:20:56,906 sleeping for 34.50s (attempt 4/10) 2017-08-09 03:21:31,441 attempt 5/10 2017-08-09 03:21:31,751 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-09 03:21:31,945 unpacking tar archive at: mozilla-central-c93fa2271ee7/testing/mozharness/ program finished with exit code 0 elapsedTime=87.141901 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 27 secs) (at 2017-08-09 03:21:31.553284) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-09 03:21:31.553797) ========= script_repo_revision: c93fa2271ee761546d469fb1edc68254f2577b5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-09 03:21:31.554268) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-09 03:21:31.554539) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-09 03:21:31.570129) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-09 03:21:31.570516) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:21:32 INFO - MultiFileLogger online at 20170809 03:21:32 in /builds/slave/test 03:21:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:21:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:21:32 INFO - {'append_to_log': False, 03:21:32 INFO - 'base_work_dir': '/builds/slave/test', 03:21:32 INFO - 'blob_upload_branch': 'Firefox', 03:21:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:21:32 INFO - 'branch': 'Firefox', 03:21:32 INFO - 'buildbot_json_path': 'buildprops.json', 03:21:32 INFO - 'code_coverage': False, 03:21:32 INFO - 'config_files': ('talos/linux_config.py',), 03:21:32 INFO - 'default_actions': ('clobber', 03:21:32 INFO - 'read-buildbot-config', 03:21:32 INFO - 'download-and-extract', 03:21:32 INFO - 'populate-webroot', 03:21:32 INFO - 'create-virtualenv', 03:21:32 INFO - 'install', 03:21:32 INFO - 'setup-mitmproxy', 03:21:32 INFO - 'run-tests'), 03:21:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:21:32 INFO - 'disable_ccov_upload': False, 03:21:32 INFO - 'download_minidump_stackwalk': True, 03:21:32 INFO - 'download_symbols': 'ondemand', 03:21:32 INFO - 'enable_stylo': False, 03:21:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:21:32 INFO - 'tooltool.py': '/tools/tooltool.py', 03:21:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:21:32 INFO - '/tools/misc-python/virtualenv.py')}, 03:21:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:21:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:21:32 INFO - 'gecko_profile': False, 03:21:32 INFO - 'gecko_profile_interval': 0, 03:21:32 INFO - 'installer_path': 'installer.exe', 03:21:32 INFO - 'log_level': 'info', 03:21:32 INFO - 'log_name': 'talos', 03:21:32 INFO - 'log_to_console': True, 03:21:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:21:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:21:32 INFO - 'opt_config_files': (), 03:21:32 INFO - 'pip_index': False, 03:21:32 INFO - 'suite': 'dromaeojs-e10s', 03:21:32 INFO - 'system_bits': '32', 03:21:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:21:32 INFO - 'title': 'talos-linux64-ix-073', 03:21:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:21:32 INFO - 'use_talos_json': True, 03:21:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:21:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:21:32 INFO - 'work_dir': 'build'} 03:21:32 INFO - [mozharness: 2017-08-09 10:21:32.382919Z] Running clobber step. 03:21:32 INFO - Running pre-action listener: _resource_record_pre_action 03:21:32 INFO - Running main action method: clobber 03:21:32 INFO - rmtree: /builds/slave/test/build 03:21:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:21:33 INFO - Running post-action listener: _resource_record_post_action 03:21:33 INFO - [mozharness: 2017-08-09 10:21:33.409596Z] Finished clobber step (success) 03:21:33 INFO - [mozharness: 2017-08-09 10:21:33.409698Z] Running read-buildbot-config step. 03:21:33 INFO - Running pre-action listener: _resource_record_pre_action 03:21:33 INFO - Running main action method: read_buildbot_config 03:21:33 INFO - Using buildbot properties: 03:21:33 INFO - { 03:21:33 INFO - "project": "", 03:21:33 INFO - "product": "firefox", 03:21:33 INFO - "who": "cbook@mozilla.com", 03:21:33 INFO - "installer_path": "public/build/target.tar.bz2", 03:21:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 03:21:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:21:33 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 03:21:33 INFO - "stage_platform": "linux64-stylosequential", 03:21:33 INFO - "basedir": "/builds/slave/test", 03:21:33 INFO - "buildnumber": 26, 03:21:33 INFO - "platform": "ubuntu64_hw_styloseq", 03:21:33 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:21:33 INFO - "slavebuilddir": "test", 03:21:33 INFO - "taskId": "cbAEWNT6Qoeny0sGy21c8A", 03:21:33 INFO - "branch": "mozilla-central", 03:21:33 INFO - "script_repo_revision": "production", 03:21:33 INFO - "revision": "c93fa2271ee761546d469fb1edc68254f2577b5a", 03:21:33 INFO - "slavename": "talos-linux64-ix-073", 03:21:33 INFO - "repo_path": "mozilla-central" 03:21:33 INFO - } 03:21:33 INFO - Finding installer, test and symbols from parent task. 03:21:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbAEWNT6Qoeny0sGy21c8A'}, attempt #1 03:21:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbAEWNT6Qoeny0sGy21c8A'}, attempt #1 03:21:34 INFO - Task dependencies: XrRJHuDETWmVhpX8_j42gA 03:21:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA'}, attempt #1 03:21:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 03:21:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.test_packages.json 03:21:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.crashreporter-symbols.zip 03:21:34 INFO - Running post-action listener: _resource_record_post_action 03:21:34 INFO - [mozharness: 2017-08-09 10:21:34.765561Z] Finished read-buildbot-config step (success) 03:21:34 INFO - [mozharness: 2017-08-09 10:21:34.765784Z] Running download-and-extract step. 03:21:34 INFO - Running pre-action listener: _resource_record_pre_action 03:21:34 INFO - Running main action method: download_and_extract 03:21:34 INFO - mkdir: /builds/slave/test/build/tests 03:21:34 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')]} 03:21:34 INFO - trying https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.test_packages.json 03:21:34 INFO - Downloading https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:21:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:21:35 INFO - Downloaded 989 bytes. 03:21:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:21:35 INFO - Using the following test package requirements: 03:21:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:21:35 INFO - u'common': [u'target.common.tests.zip'], 03:21:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:21:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:21:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:21:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:21:35 INFO - u'mozbase': [u'target.common.tests.zip'], 03:21:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:21:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:21:35 INFO - u'web-platform': [u'target.common.tests.zip', 03:21:35 INFO - u'target.web-platform.tests.tar.gz'], 03:21:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:21:35 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:21:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip 03:21:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:21:35 INFO - Fetch https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip into memory 03:21:36 INFO - Content-Length response header: 54285505 03:21:36 INFO - Bytes received: 54285505 03:21:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:21:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip 03:21:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:21:47 INFO - Fetch https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip into memory 03:21:48 INFO - Content-Length response header: 54285505 03:21:48 INFO - Bytes received: 54285505 03:22:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.talos.tests.zip 03:22:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:22:00 INFO - Fetch https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.talos.tests.zip into memory 03:22:01 INFO - Content-Length response header: 14496008 03:22:01 INFO - Bytes received: 14496008 03:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:01 INFO - trying https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 03:22:01 INFO - Downloading https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:22:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:22:02 INFO - Downloaded 61866904 bytes. 03:22:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 03:22:02 INFO - mkdir: /builds/slave/test/properties 03:22:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:22:02 INFO - Writing to file /builds/slave/test/properties/build_url 03:22:02 INFO - Contents: 03:22:02 INFO - build_url:https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 03:22:02 INFO - Running post-action listener: _resource_record_post_action 03:22:02 INFO - Running post-action listener: set_extra_try_arguments 03:22:02 INFO - [mozharness: 2017-08-09 10:22:02.729819Z] Finished download-and-extract step (success) 03:22:02 INFO - [mozharness: 2017-08-09 10:22:02.729918Z] Running populate-webroot step. 03:22:02 INFO - Running pre-action listener: _resource_record_pre_action 03:22:02 INFO - Running main action method: populate_webroot 03:22:02 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:22:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:22:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:22:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:22:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:22:02 INFO - 'tests': ['damp', 'tps']}, 03:22:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:22:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:22:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:22:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:22:02 INFO - 'other-e10s': {'tests': ['a11yr', 03:22:02 INFO - 'ts_paint', 03:22:02 INFO - 'tpaint', 03:22:02 INFO - 'sessionrestore', 03:22:02 INFO - 'sessionrestore_many_windows', 03:22:02 INFO - 'sessionrestore_no_auto_restore', 03:22:02 INFO - 'tabpaint']}, 03:22:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:22:02 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:22:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:22:02 INFO - 'tsvgr_opacity', 03:22:02 INFO - 'tart', 03:22:02 INFO - 'tscrollx', 03:22:02 INFO - 'tsvg_static']}, 03:22:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:22:02 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:22:02 INFO - 'talos_options': ['--mitmproxy', 03:22:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:22:02 INFO - '--firstNonBlankPaint'], 03:22:02 INFO - 'tests': ['tp6_google', 03:22:02 INFO - 'tp6_youtube', 03:22:02 INFO - 'tp6_amazon', 03:22:02 INFO - 'tp6_facebook']}, 03:22:02 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:22:02 INFO - 'talos_options': ['--stylo', 03:22:02 INFO - '--mitmproxy', 03:22:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:22:02 INFO - '--firstNonBlankPaint'], 03:22:02 INFO - 'tests': ['tp6_google', 03:22:02 INFO - 'tp6_youtube', 03:22:02 INFO - 'tp6_amazon', 03:22:02 INFO - 'tp6_facebook']}, 03:22:02 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:22:02 INFO - 'talos_options': ['--stylo', 03:22:02 INFO - '--stylo-threads=1', 03:22:02 INFO - '--mitmproxy', 03:22:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:22:02 INFO - '--firstNonBlankPaint'], 03:22:02 INFO - 'tests': ['tp6_google', 03:22:02 INFO - 'tp6_youtube', 03:22:02 INFO - 'tp6_amazon', 03:22:02 INFO - 'tp6_facebook']}, 03:22:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:22:02 INFO - 'talos_options': ['--xperf_path', 03:22:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:22:02 INFO - 'tests': ['tp5n']}}} 03:22:02 INFO - Running post-action listener: _resource_record_post_action 03:22:02 INFO - [mozharness: 2017-08-09 10:22:02.738322Z] Finished populate-webroot step (success) 03:22:02 INFO - [mozharness: 2017-08-09 10:22:02.738412Z] Running create-virtualenv step. 03:22:02 INFO - Running pre-action listener: _resource_record_pre_action 03:22:02 INFO - Running main action method: create_virtualenv 03:22:02 INFO - Creating virtualenv /builds/slave/test/build/venv 03:22:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:22:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:22:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:22:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:22:02 INFO - Using real prefix '/usr' 03:22:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:22:03 INFO - Installing distribute.............................................................................................................................................................................................done. 03:22:05 INFO - Installing pip.................done. 03:22:05 INFO - Return code: 0 03:22:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:22:05 INFO - Reading from file tmpfile_stdout 03:22:05 INFO - Output received: 03:22:05 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 03:22:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:05 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 0x9d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd43b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', '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 03:22:05 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 03:22:05 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 03:22:05 INFO - Using env: {'DISPLAY': ':0', 03:22:05 INFO - 'HOME': '/home/cltbld', 03:22:05 INFO - 'LANG': 'en_US.UTF-8', 03:22:05 INFO - 'LANGUAGE': 'en_US:en', 03:22:05 INFO - 'LOGNAME': 'cltbld', 03:22:05 INFO - 'MAIL': '/var/mail/cltbld', 03:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:05 INFO - 'MOZ_NO_REMOTE': '1', 03:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:05 INFO - 'NO_EM_RESTART': '1', 03:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:05 INFO - 'PWD': '/builds/slave/test', 03:22:05 INFO - 'SHELL': '/bin/bash', 03:22:05 INFO - 'SHLVL': '1', 03:22:05 INFO - 'TERM': 'linux', 03:22:05 INFO - 'TMOUT': '86400', 03:22:05 INFO - 'USER': 'cltbld', 03:22:05 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:05 INFO - '_': '/tools/buildbot/bin/python'} 03:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:22:05 INFO - Cleaning up... 03:22:05 INFO - Return code: 0 03:22:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:05 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 0x9d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd43b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', '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 03:22:05 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 03:22:05 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 03:22:05 INFO - Using env: {'DISPLAY': ':0', 03:22:05 INFO - 'HOME': '/home/cltbld', 03:22:05 INFO - 'LANG': 'en_US.UTF-8', 03:22:05 INFO - 'LANGUAGE': 'en_US:en', 03:22:05 INFO - 'LOGNAME': 'cltbld', 03:22:05 INFO - 'MAIL': '/var/mail/cltbld', 03:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:05 INFO - 'MOZ_NO_REMOTE': '1', 03:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:05 INFO - 'NO_EM_RESTART': '1', 03:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:05 INFO - 'PWD': '/builds/slave/test', 03:22:05 INFO - 'SHELL': '/bin/bash', 03:22:05 INFO - 'SHLVL': '1', 03:22:05 INFO - 'TERM': 'linux', 03:22:05 INFO - 'TMOUT': '86400', 03:22:05 INFO - 'USER': 'cltbld', 03:22:05 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:05 INFO - '_': '/tools/buildbot/bin/python'} 03:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:05 INFO - Downloading/unpacking psutil>=3.1.1 03:22:05 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 03:22:05 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 03:22:05 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 03:22:05 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 03:22:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:22:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:07 INFO - Installing collected packages: psutil 03:22:07 INFO - Running setup.py install for psutil 03:22:07 INFO - building 'psutil._psutil_linux' extension 03:22:07 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 03:22:07 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 03:22:07 INFO - building 'psutil._psutil_posix' extension 03:22:07 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 03:22:07 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 03:22:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:07 INFO - Successfully installed psutil 03:22:07 INFO - Cleaning up... 03:22:07 INFO - Return code: 0 03:22:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:07 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 0x9d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd43b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', '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 03:22:07 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 03:22:07 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 03:22:07 INFO - Using env: {'DISPLAY': ':0', 03:22:07 INFO - 'HOME': '/home/cltbld', 03:22:07 INFO - 'LANG': 'en_US.UTF-8', 03:22:07 INFO - 'LANGUAGE': 'en_US:en', 03:22:07 INFO - 'LOGNAME': 'cltbld', 03:22:07 INFO - 'MAIL': '/var/mail/cltbld', 03:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:07 INFO - 'MOZ_NO_REMOTE': '1', 03:22:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:07 INFO - 'NO_EM_RESTART': '1', 03:22:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:07 INFO - 'PWD': '/builds/slave/test', 03:22:07 INFO - 'SHELL': '/bin/bash', 03:22:07 INFO - 'SHLVL': '1', 03:22:07 INFO - 'TERM': 'linux', 03:22:07 INFO - 'TMOUT': '86400', 03:22:07 INFO - 'USER': 'cltbld', 03:22:07 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:07 INFO - '_': '/tools/buildbot/bin/python'} 03:22:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:22:08 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 03:22:08 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 03:22:08 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 03:22:08 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 03:22:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:22:09 INFO - Installing collected packages: mozsystemmonitor 03:22:09 INFO - Running setup.py install for mozsystemmonitor 03:22:09 INFO - Successfully installed mozsystemmonitor 03:22:09 INFO - Cleaning up... 03:22:09 INFO - Return code: 0 03:22:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:09 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 0x9d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd43b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', '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 03:22:09 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 03:22:09 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 03:22:09 INFO - Using env: {'DISPLAY': ':0', 03:22:09 INFO - 'HOME': '/home/cltbld', 03:22:09 INFO - 'LANG': 'en_US.UTF-8', 03:22:09 INFO - 'LANGUAGE': 'en_US:en', 03:22:09 INFO - 'LOGNAME': 'cltbld', 03:22:09 INFO - 'MAIL': '/var/mail/cltbld', 03:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:09 INFO - 'MOZ_NO_REMOTE': '1', 03:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:09 INFO - 'NO_EM_RESTART': '1', 03:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:09 INFO - 'PWD': '/builds/slave/test', 03:22:09 INFO - 'SHELL': '/bin/bash', 03:22:09 INFO - 'SHLVL': '1', 03:22:09 INFO - 'TERM': 'linux', 03:22:09 INFO - 'TMOUT': '86400', 03:22:09 INFO - 'USER': 'cltbld', 03:22:09 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:09 INFO - '_': '/tools/buildbot/bin/python'} 03:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:09 INFO - Downloading/unpacking jsonschema==2.5.1 03:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:22:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:22:10 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 03:22:10 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 03:22:10 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 03:22:10 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 03:22:10 INFO - Downloading functools32-3.2.3-2.tar.gz 03:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:22:10 INFO - warning: no files found matching '*.txt' 03:22:10 INFO - no previously-included directories found matching 'build' 03:22:10 INFO - no previously-included directories found matching 'dist' 03:22:10 INFO - no previously-included directories found matching '.git*' 03:22:10 INFO - Installing collected packages: jsonschema, functools32 03:22:11 INFO - Running setup.py install for functools32 03:22:11 INFO - warning: no files found matching '*.txt' 03:22:11 INFO - no previously-included directories found matching 'build' 03:22:11 INFO - no previously-included directories found matching 'dist' 03:22:11 INFO - no previously-included directories found matching '.git*' 03:22:11 INFO - Successfully installed jsonschema functools32 03:22:11 INFO - Cleaning up... 03:22:11 INFO - Return code: 0 03:22:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:22:11 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')]} 03:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:11 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')]} 03:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:11 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 0x9d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd43b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', '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 03:22:11 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 03:22:11 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 03:22:11 INFO - Using env: {'DISPLAY': ':0', 03:22:11 INFO - 'HOME': '/home/cltbld', 03:22:11 INFO - 'LANG': 'en_US.UTF-8', 03:22:11 INFO - 'LANGUAGE': 'en_US:en', 03:22:11 INFO - 'LOGNAME': 'cltbld', 03:22:11 INFO - 'MAIL': '/var/mail/cltbld', 03:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:11 INFO - 'MOZ_NO_REMOTE': '1', 03:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:11 INFO - 'NO_EM_RESTART': '1', 03:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:11 INFO - 'PWD': '/builds/slave/test', 03:22:11 INFO - 'SHELL': '/bin/bash', 03:22:11 INFO - 'SHLVL': '1', 03:22:11 INFO - 'TERM': 'linux', 03:22:11 INFO - 'TMOUT': '86400', 03:22:11 INFO - 'USER': 'cltbld', 03:22:11 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:11 INFO - '_': '/tools/buildbot/bin/python'} 03:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:22:11 INFO - Cleaning up... 03:22:11 INFO - Return code: 0 03:22:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:22:11 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')]} 03:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:11 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')]} 03:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:11 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 0x9d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd43b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', '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 03:22:11 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 03:22:11 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 03:22:11 INFO - Using env: {'DISPLAY': ':0', 03:22:11 INFO - 'HOME': '/home/cltbld', 03:22:11 INFO - 'LANG': 'en_US.UTF-8', 03:22:11 INFO - 'LANGUAGE': 'en_US:en', 03:22:11 INFO - 'LOGNAME': 'cltbld', 03:22:11 INFO - 'MAIL': '/var/mail/cltbld', 03:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:11 INFO - 'MOZ_NO_REMOTE': '1', 03:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:11 INFO - 'NO_EM_RESTART': '1', 03:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:11 INFO - 'PWD': '/builds/slave/test', 03:22:11 INFO - 'SHELL': '/bin/bash', 03:22:11 INFO - 'SHLVL': '1', 03:22:11 INFO - 'TERM': 'linux', 03:22:11 INFO - 'TMOUT': '86400', 03:22:11 INFO - 'USER': 'cltbld', 03:22:11 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:11 INFO - '_': '/tools/buildbot/bin/python'} 03:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:11 INFO - Downloading/unpacking blobuploader==1.2.4 03:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:12 INFO - Downloading blobuploader-1.2.4.tar.gz 03:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:22:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:22:12 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 03:22:12 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 03:22:12 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 03:22:12 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 03:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:22:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:22:13 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 03:22:13 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 03:22:13 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 03:22:13 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 03:22:13 INFO - Downloading docopt-0.6.1.tar.gz 03:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:22:13 INFO - Installing collected packages: blobuploader, requests, docopt 03:22:13 INFO - Running setup.py install for blobuploader 03:22:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:22:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:22:13 INFO - Running setup.py install for requests 03:22:13 INFO - Running setup.py install for docopt 03:22:13 INFO - Successfully installed blobuploader requests docopt 03:22:13 INFO - Cleaning up... 03:22:13 INFO - Return code: 0 03:22:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:13 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 0x9d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd43b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', '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 03:22:13 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 03:22:13 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 03:22:13 INFO - Using env: {'DISPLAY': ':0', 03:22:13 INFO - 'HOME': '/home/cltbld', 03:22:13 INFO - 'LANG': 'en_US.UTF-8', 03:22:13 INFO - 'LANGUAGE': 'en_US:en', 03:22:13 INFO - 'LOGNAME': 'cltbld', 03:22:13 INFO - 'MAIL': '/var/mail/cltbld', 03:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:13 INFO - 'MOZ_NO_REMOTE': '1', 03:22:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:13 INFO - 'NO_EM_RESTART': '1', 03:22:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:13 INFO - 'PWD': '/builds/slave/test', 03:22:13 INFO - 'SHELL': '/bin/bash', 03:22:13 INFO - 'SHLVL': '1', 03:22:13 INFO - 'TERM': 'linux', 03:22:13 INFO - 'TMOUT': '86400', 03:22:13 INFO - 'USER': 'cltbld', 03:22:13 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:13 INFO - '_': '/tools/buildbot/bin/python'} 03:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:14 INFO - Running setup.py (path:/tmp/pip-ktBMvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:14 INFO - Running setup.py (path:/tmp/pip-c9MH4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:14 INFO - Running setup.py (path:/tmp/pip-r2SxyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:14 INFO - Running setup.py (path:/tmp/pip-YgTED7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:14 INFO - Running setup.py (path:/tmp/pip-7kgRyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:14 INFO - Running setup.py (path:/tmp/pip-fX1zaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:14 INFO - Running setup.py (path:/tmp/pip-E1K8HD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:14 INFO - Running setup.py (path:/tmp/pip-3hLmGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:14 INFO - Running setup.py (path:/tmp/pip-dxCuA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:14 INFO - Running setup.py (path:/tmp/pip-OzHbfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:14 INFO - Running setup.py (path:/tmp/pip-PZv81q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:14 INFO - Running setup.py (path:/tmp/pip-Fb4QT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:14 INFO - Running setup.py (path:/tmp/pip-3kYc6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:15 INFO - Running setup.py (path:/tmp/pip-Cssf7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:15 INFO - Running setup.py (path:/tmp/pip-yGhiAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:15 INFO - Running setup.py (path:/tmp/pip-O3xzZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:15 INFO - Running setup.py (path:/tmp/pip-XiRPO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:22:15 INFO - Running setup.py install for manifestparser 03:22:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Running setup.py install for mozcrash 03:22:15 INFO - Running setup.py install for mozdebug 03:22:15 INFO - Running setup.py install for mozdevice 03:22:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Running setup.py install for mozfile 03:22:15 INFO - Running setup.py install for mozhttpd 03:22:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:22:15 INFO - Running setup.py install for mozinfo 03:22:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Running setup.py install for mozInstall 03:22:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Running setup.py install for mozleak 03:22:16 INFO - Running setup.py install for mozlog 03:22:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Running setup.py install for moznetwork 03:22:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Running setup.py install for mozprocess 03:22:16 INFO - Running setup.py install for mozprofile 03:22:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Running setup.py install for mozrunner 03:22:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:22:16 INFO - Running setup.py install for mozscreenshot 03:22:16 INFO - Running setup.py install for moztest 03:22:17 INFO - Running setup.py install for mozversion 03:22:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:22:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:22:17 INFO - Cleaning up... 03:22:17 INFO - Return code: 0 03:22:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22: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')]} 03:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:17 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 0x9d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd43b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', '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 03:22:17 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 03:22:17 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 03:22:17 INFO - Using env: {'DISPLAY': ':0', 03:22:17 INFO - 'HOME': '/home/cltbld', 03:22:17 INFO - 'LANG': 'en_US.UTF-8', 03:22:17 INFO - 'LANGUAGE': 'en_US:en', 03:22:17 INFO - 'LOGNAME': 'cltbld', 03:22:17 INFO - 'MAIL': '/var/mail/cltbld', 03:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:17 INFO - 'MOZ_NO_REMOTE': '1', 03:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:17 INFO - 'NO_EM_RESTART': '1', 03:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:17 INFO - 'PWD': '/builds/slave/test', 03:22:17 INFO - 'SHELL': '/bin/bash', 03:22:17 INFO - 'SHLVL': '1', 03:22:17 INFO - 'TERM': 'linux', 03:22:17 INFO - 'TMOUT': '86400', 03:22:17 INFO - 'USER': 'cltbld', 03:22:17 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:17 INFO - '_': '/tools/buildbot/bin/python'} 03:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:17 INFO - Running setup.py (path:/tmp/pip-LIzBXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:17 INFO - Running setup.py (path:/tmp/pip-KYZHsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:17 INFO - Running setup.py (path:/tmp/pip-TjkLJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:17 INFO - Running setup.py (path:/tmp/pip-2cOQyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:17 INFO - Running setup.py (path:/tmp/pip-2Xuv_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:17 INFO - Running setup.py (path:/tmp/pip-9BE_4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:17 INFO - Running setup.py (path:/tmp/pip-GsOYMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:17 INFO - Running setup.py (path:/tmp/pip-oDFzcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:17 INFO - Running setup.py (path:/tmp/pip-JN1pJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:17 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)) 03:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:17 INFO - Running setup.py (path:/tmp/pip-CDR32K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:18 INFO - Running setup.py (path:/tmp/pip-waSV2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:18 INFO - Running setup.py (path:/tmp/pip-Uojmud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:18 INFO - Running setup.py (path:/tmp/pip-zQa_Wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:18 INFO - Running setup.py (path:/tmp/pip-md3ago-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:18 INFO - Running setup.py (path:/tmp/pip-BSLzEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:18 INFO - Running setup.py (path:/tmp/pip-RSk1WJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:18 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)) 03:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:18 INFO - Running setup.py (path:/tmp/pip-4q1azo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:18 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)) 03:22:18 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)) 03:22:18 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)) 03:22:18 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)) 03:22:18 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)) 03:22:18 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)) 03:22:18 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)) 03:22:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:18 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 03:22:18 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 03:22:18 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 03:22:18 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 03:22:19 INFO - Downloading blessings-1.6.tar.gz 03:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:22:19 INFO - Installing collected packages: blessings 03:22:19 INFO - Running setup.py install for blessings 03:22:19 INFO - Successfully installed blessings 03:22:19 INFO - Cleaning up... 03:22:19 INFO - Return code: 0 03:22:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:22:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:20 INFO - Reading from file tmpfile_stdout 03:22:20 INFO - Current package versions: 03:22:20 INFO - argparse == 1.2.1 03:22:20 INFO - blessings == 1.6 03:22:20 INFO - blobuploader == 1.2.4 03:22:20 INFO - docopt == 0.6.1 03:22:20 INFO - functools32 == 3.2.3-2 03:22:20 INFO - jsonschema == 2.5.1 03:22:20 INFO - manifestparser == 1.1 03:22:20 INFO - mozInstall == 1.13 03:22:20 INFO - mozcrash == 1.0 03:22:20 INFO - mozdebug == 0.1 03:22:20 INFO - mozdevice == 0.51 03:22:20 INFO - mozfile == 1.2 03:22:20 INFO - mozhttpd == 0.7 03:22:20 INFO - mozinfo == 0.10 03:22:20 INFO - mozleak == 0.1 03:22:20 INFO - mozlog == 3.5 03:22:20 INFO - moznetwork == 0.27 03:22:20 INFO - mozprocess == 0.25 03:22:20 INFO - mozprofile == 0.28 03:22:20 INFO - mozrunner == 6.13 03:22:20 INFO - mozscreenshot == 0.1 03:22:20 INFO - mozsystemmonitor == 0.3 03:22:20 INFO - moztest == 0.8 03:22:20 INFO - mozversion == 1.4 03:22:20 INFO - psutil == 3.1.1 03:22:20 INFO - requests == 1.2.3 03:22:20 INFO - wsgiref == 0.1.2 03:22:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:20 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')]} 03:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:20 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')]} 03:22:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:20 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 0x9d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd43b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', '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 03:22:20 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 03:22:20 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 03:22:20 INFO - Using env: {'DISPLAY': ':0', 03:22:20 INFO - 'HOME': '/home/cltbld', 03:22:20 INFO - 'LANG': 'en_US.UTF-8', 03:22:20 INFO - 'LANGUAGE': 'en_US:en', 03:22:20 INFO - 'LOGNAME': 'cltbld', 03:22:20 INFO - 'MAIL': '/var/mail/cltbld', 03:22:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:20 INFO - 'MOZ_NO_REMOTE': '1', 03:22:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:20 INFO - 'NO_EM_RESTART': '1', 03:22:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:20 INFO - 'PWD': '/builds/slave/test', 03:22:20 INFO - 'SHELL': '/bin/bash', 03:22:20 INFO - 'SHLVL': '1', 03:22:20 INFO - 'TERM': 'linux', 03:22:20 INFO - 'TMOUT': '86400', 03:22:20 INFO - 'USER': 'cltbld', 03:22:20 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:20 INFO - '_': '/tools/buildbot/bin/python'} 03:22:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:20 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)) 03:22:20 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)) 03:22:20 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)) 03:22:20 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)) 03:22:20 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)) 03:22:20 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)) 03:22:20 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)) 03:22:20 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)) 03:22:20 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)) 03:22:20 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:22:20 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 03:22:20 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 03:22:20 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 03:22:20 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 03:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:22:21 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)) 03:22:21 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)) 03:22:21 INFO - Installing collected packages: simplejson 03:22:21 INFO - Running setup.py install for simplejson 03:22:21 INFO - building 'simplejson._speedups' extension 03:22:21 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 03:22:22 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 03:22:22 INFO - Successfully installed simplejson 03:22:22 INFO - Cleaning up... 03:22:22 INFO - Return code: 0 03:22:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:22 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 0x9d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd43b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', '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 03:22:22 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 03:22:22 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 03:22:22 INFO - Using env: {'DISPLAY': ':0', 03:22:22 INFO - 'HOME': '/home/cltbld', 03:22:22 INFO - 'LANG': 'en_US.UTF-8', 03:22:22 INFO - 'LANGUAGE': 'en_US:en', 03:22:22 INFO - 'LOGNAME': 'cltbld', 03:22:22 INFO - 'MAIL': '/var/mail/cltbld', 03:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:22 INFO - 'MOZ_NO_REMOTE': '1', 03:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:22 INFO - 'NO_EM_RESTART': '1', 03:22:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:22 INFO - 'PWD': '/builds/slave/test', 03:22:22 INFO - 'SHELL': '/bin/bash', 03:22:22 INFO - 'SHLVL': '1', 03:22:22 INFO - 'TERM': 'linux', 03:22:22 INFO - 'TMOUT': '86400', 03:22:22 INFO - 'USER': 'cltbld', 03:22:22 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:22 INFO - '_': '/tools/buildbot/bin/python'} 03:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:22:22 INFO - Cleaning up... 03:22:22 INFO - Return code: 0 03:22:22 INFO - Running post-action listener: _resource_record_post_action 03:22:22 INFO - Running post-action listener: _start_resource_monitoring 03:22:22 INFO - Starting resource monitoring. 03:22:22 INFO - [mozharness: 2017-08-09 10:22:22.842318Z] Finished create-virtualenv step (success) 03:22:22 INFO - [mozharness: 2017-08-09 10:22:22.842839Z] Running install step. 03:22:22 INFO - Running pre-action listener: _resource_record_pre_action 03:22:22 INFO - Running main action method: install 03:22:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:23 INFO - Reading from file tmpfile_stdout 03:22:23 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:23 INFO - Reading from file tmpfile_stdout 03:22:23 INFO - Output received: 03:22:23 INFO - Usage: mozinstall [options] installer 03:22:23 INFO - Options: 03:22:23 INFO - -h, --help show this help message and exit 03:22:23 INFO - -d DEST, --destination=DEST 03:22:23 INFO - Directory to install application into. [default: 03:22:23 INFO - "/builds/slave/test"] 03:22:23 INFO - --app=APP Application being installed. [default: firefox] 03:22:23 INFO - mkdir: /builds/slave/test/build/application 03:22:23 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'] 03:22:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:22:41 INFO - Reading from file tmpfile_stdout 03:22:41 INFO - Output received: 03:22:41 INFO - /builds/slave/test/build/application/firefox/firefox 03:22:41 INFO - Running post-action listener: _resource_record_post_action 03:22:41 INFO - [mozharness: 2017-08-09 10:22:41.255652Z] Finished install step (success) 03:22:41 INFO - [mozharness: 2017-08-09 10:22:41.255797Z] Running setup-mitmproxy step. 03:22:41 INFO - Running pre-action listener: _resource_record_pre_action 03:22:41 INFO - Running main action method: setup_mitmproxy 03:22:41 INFO - Skipping: mitmproxy is not required 03:22:41 INFO - Running post-action listener: _resource_record_post_action 03:22:41 INFO - [mozharness: 2017-08-09 10:22:41.256329Z] Finished setup-mitmproxy step (success) 03:22:41 INFO - [mozharness: 2017-08-09 10:22:41.256459Z] Running run-tests step. 03:22:41 INFO - Running pre-action listener: _resource_record_pre_action 03:22:41 INFO - Running pre-action listener: _set_gcov_prefix 03:22:41 INFO - Running main action method: run_tests 03:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:41 INFO - Python 2.7.3 03:22:41 INFO - Return code: 0 03:22:41 INFO - grabbing minidump binary from tooltool 03:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:41 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0xd46f00>, '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 0xd41ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd42050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 03:22:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:22:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 03:22:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:41 INFO - Return code: 0 03:22:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:22:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:41 INFO - ENV: RUST_BACKTRACE is now full 03:22:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:22:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--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 03:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-073 --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 03:22:41 INFO - Using env: {'DISPLAY': ':0', 03:22:41 INFO - 'HOME': '/home/cltbld', 03:22:41 INFO - 'LANG': 'en_US.UTF-8', 03:22:41 INFO - 'LANGUAGE': 'en_US:en', 03:22:41 INFO - 'LOGNAME': 'cltbld', 03:22:41 INFO - 'MAIL': '/var/mail/cltbld', 03:22:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:41 INFO - 'MOZ_NO_REMOTE': '1', 03:22:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:41 INFO - 'NO_EM_RESTART': '1', 03:22:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:41 INFO - 'PWD': '/builds/slave/test', 03:22:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:41 INFO - 'RUST_BACKTRACE': 'full', 03:22:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:22:41 INFO - 'SHELL': '/bin/bash', 03:22:41 INFO - 'SHLVL': '1', 03:22:41 INFO - 'STYLO_FORCE_ENABLED': '1', 03:22:41 INFO - 'TERM': 'linux', 03:22:41 INFO - 'TMOUT': '86400', 03:22:41 INFO - 'USER': 'cltbld', 03:22:41 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685', 03:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:41 INFO - '_': '/tools/buildbot/bin/python'} 03:22:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--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 03:22:41 INFO - mozversion application_buildid: 20170809093407 03:22:41 INFO - mozversion application_changeset: c93fa2271ee761546d469fb1edc68254f2577b5a 03:22:41 INFO - mozversion application_display_name: Nightly 03:22:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:41 INFO - mozversion application_name: Firefox 03:22:41 INFO - mozversion application_remotingname: firefox 03:22:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:22:41 INFO - mozversion application_vendor: Mozilla 03:22:41 INFO - mozversion application_version: 57.0a1 03:22:41 INFO - mozversion platform_buildid: 20170809093407 03:22:41 INFO - mozversion platform_changeset: c93fa2271ee761546d469fb1edc68254f2577b5a 03:22:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:22:41 INFO - mozversion platform_version: 57.0a1 03:22:41 INFO - using testdate: 1502274161 03:22:41 INFO - actual date: 1502274161 03:22:41 INFO - starting webserver on 'localhost:53339' 03:22:41 INFO - SUITE-START | Running 2 tests 03:22:41 INFO - TEST-START | dromaeo_css 03:22:41 INFO - Initialising browser for dromaeo_css test... 03:22:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53339/getInfo.html -profile /tmp/tmp_YvXRP/profile 03:22:41 INFO - TEST-INFO | started process 27952 (/builds/slave/test/build/application/firefox/firefox http://localhost:53339/getInfo.html) 03:22:47 INFO - TEST-INFO | 27952: exit 0 03:22:47 INFO - Browser initialized. 03:22:47 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:47 INFO - TEST-INFO | started process 28181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_YvXRP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:48 INFO - PID 28181 | 03:22:48 INFO - PID 28181 | (/builds/slave/test/build/application/firefox/firefox:28235): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:22:48 INFO - PID 28181 | 03:22:58 INFO - PID 28181 | RSS: Main: 170516480 03:22:58 INFO - PID 28181 | 03:25:58 INFO - PID 28181 | Cycle 1(1): loaded http://localhost:53339/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53339/tests/dromaeo/cssquery-ext.html) 03:25:59 INFO - PID 28181 | RSS: Main: 175042560 03:25:59 INFO - PID 28181 | 03:29:01 INFO - PID 28181 | Cycle 1(1): loaded http://localhost:53339/tests/dromaeo/cssquery-ext.html (next: http://localhost:53339/tests/dromaeo/cssquery-jquery.html) 03:29:02 INFO - PID 28181 | RSS: Main: 172699648 03:29:02 INFO - PID 28181 | 03:32:04 INFO - PID 28181 | Cycle 1(1): loaded http://localhost:53339/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53339/tests/dromaeo/cssquery-mootools.html) 03:32:04 INFO - PID 28181 | RSS: Main: 175005696 03:32:04 INFO - PID 28181 | 03:35:06 INFO - PID 28181 | Cycle 1(1): loaded http://localhost:53339/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53339/tests/dromaeo/cssquery-prototype.html) 03:35:07 INFO - PID 28181 | RSS: Main: 173449216 03:35:07 INFO - PID 28181 | 03:38:11 INFO - PID 28181 | Cycle 1(1): loaded http://localhost:53339/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53339/tests/dromaeo/cssquery-yui.html) 03:38:11 INFO - PID 28181 | RSS: Main: 176644096 03:38:11 INFO - PID 28181 | 03:41:13 INFO - PID 28181 | Cycle 1(1): loaded http://localhost:53339/tests/dromaeo/cssquery-yui.html (next: http://localhost:53339/tests/dromaeo/cssquery-dojo.html) 03:41:13 INFO - PID 28181 | RSS: Main: 177467392 03:41:13 INFO - PID 28181 | 03:41:13 INFO - PID 28181 | __start_tp_report 03:41:13 INFO - PID 28181 | _x_x_mozilla_page_load 03:41:13 INFO - PID 28181 | _x_x_mozilla_page_load_details 03:41:13 INFO - PID 28181 | |i|pagename|runs| 03:41:13 INFO - PID 28181 | |0;dojo.html;3094.1336425800782;3123.687631236876;3138.262508310548;3156.463401221792;3196.6164060312767;6119.112728654348;6150.077488376745;6155.3229144794095;6170.352113028135;6331;6219.689015549227;6251;6266.592671476362;6287.968560157193;6319.715612797424;5664.320281566207;5725.685087320196;5733.942660573382;5807.070868661015;5807.477327040566;5571.247881535981;5623.718814059301;5639;5646.830595082133;5716.428357164292;5723.685197314146;5738.483536481715;5743.195952566644;5758.078707406837;5769.971150144243;5667.688277144755;5686.2039314496005;5760.423957604248;5769.3076830780155;5774.422557744234;5627.887442251173;5629.8592535186535;5685.403032681592;5701.971490142523;5744.798932037359;6047.971844786352;6069.6054756441135;6149.077638354238;6166;6222.533310001768;6719.7312107515645;6751.189857217166;6759.831004224885;6761.425278851303;6761.966190169067;6728.091707619457;6738.52830301879;6766.221884483249;6813.761518346883;6823.93176068238;6102.969485152635;6237.563370564062;6287;6354;6355.110284560165;6866.656667166621;6883.3116688330765;6904.965475172592;6912.723491060301;6916.170059592881;6492.5455218134275;6551.508636852255;6572.309907459744;6573.046908198285;6583.868322633521;6654.50091243149;6670.899936500957;6688.163979502562;6692.732290708414;6692.799216023527;6627.204735431779;6654.268030516736;6654.933450665529;6667.899981500282;6669.366410191121;6667.466602671775;6671.766488172988;6715.093462382516;6720.563163394364;6773.119494465688;5407.918881216785;5431.2396264522995;5512.118061110203;5524.8342549723575;5529.281193444826;4581.93127103087;4599.977000115045;4611.700239484489;4653.278741795028;4697.177993851063;5872.708004239061;5882.970480165954;5890.734916928663;5892.852678683067;5892.941070589325;6758.155230596176;6782.525223234425;6793.184817821893;6793.660316984131;6806.489513286523;5780.1907732917425;5854.912176317359;5884.6469211847425;5890.322612899486;5902.439268269508;5791.710414479259;5905.881882362329;5909.084091965752;6000.279966404059;6008.128821320972;6298.307186209513;6302.401271879165;6312.52656050798;6425.871482570323;6437.163168788027;6543.738250469928;6591.934080659132;6634.137562116894;6645.036469711961;6658.766943156967;6385.553011289165;6410.583312084699;6435.163428754232;6450.161479007699;6558.442532384801;6535.803925882231;6555.80332590023;6564.5076619252795;6634.933650663432;6687.598744075176;6605.966970165118;6683.832904177434;6720.663966801542;6722.092517510074;6778.762743303961;6459.806205813833;6475.740970361321;6499.5775274607;6560;6573.474122070318;6535.379138981789;6560.704768285344;6578.440832529097;6588.110605068255;6598.967005165136;6396;6422.775202868064;6513.348665133449;6524.934750652623;6527.836804079936;6425.325340839145;6560.507961902781;6622.569532980341;6633.800985970429;6637.402633762985;6273.466755325849;6285.59143655661;6293.496520278458;6298.338674439118;6346.904796427965 03:41:13 INFO - PID 28181 | |1;ext.html;828.8549503836831;835.5404527509876;861.4443683823933;882.5587206396804;894.4812009034761;43092.78453607731;44189.558104418946;44197;44230.77884610581;44281.89295267616;39648.801755991255;39809.20381592366;39901;40009.19981600366;40171.19657606846;6942.757003504866;7043.295670432967;7067.787966361017;7082.2563630818795;7107.78676639699;6818.727250909958;6885.931140688579;6890.931090689079;6901.551399159063;6902.723891044352;7724.845503089935;7738.342240909529;7743.961280193591;7745.728899488519;7784.1826608206175;7562.848743025109;7623.6569354378635;7648.961755191216;7658.425618078666;7659.387249020093;7605.201453847321;7746;7757.224277572254;7791.4935529190425;7796.649150788228;41083.58916410828;41390.1721965562;41544.79227603873;41663.583364166276;41736.582634173574;3541.5750109986707;3576.982115089434;3587.1749497615433;3595.694365978895;3606.1886075633033;32089.358212835847;32165.517517237262;32180.356392872247;32252.193695157574;32253.193670158198;5023.296738456619;5093.949060509384;5104.336436263299;5111.897762044739;5123.564497017758;189844.0507797466;191513.25460236214;191579;192337;192773;50553.49446505561;51124;51173;51201.23198152031;51316.23025654618;53446.73276633592;53463.73268133635;53475.663108421824;53537.46462535403;53653.7317313411;15554.922225389028;15991.840081599268;15998.200089995686;16064.196790160446;16082.115483648278;12828.294443805493;12972;13000.414981325863;13003.609891703263;13017;14699.926500367643;14863.479778207928;14898.106113633217;15051.849481505045;15070.2669193042;5864.70676466175;5980.162777211194;5990.820275391745;6015.488683461867;6028.125921741323;1014.7818219082933;1015.6851376073441;1023.150784848574;1024.9538770755184;1040.7398150462384;23093.653595196087;23493;23497.76502234956;23580.70306133179;23605.88197059004;1107.379867274329;1115.403259256294;1117.4524483003433;1121.5906194239208;1127.9154063445274;12357.75284494305;12418.875811241953;12467.87532124667;12526.686832829253;16182.919085404497;241507;241731;242447;242983;243498;51366;52204.47795521996;52326.73836630792;59021;59206;1450.3255985966575;1475.181274392701;1482.5552334299666;1498.4755335632442;1499.130504307499;27983.58024629673;28150.2962425941;28351.43297134087;28406.147815565568;28791.280217994718;35120;35151.47272790859;35260.294794104484;35430.46854297239;35653.28693426169;196415;196735;197192;197274;197560;33970.83014584911;35305.646943530235;35461.93614191579;35517.467237991965;35571.64428355683;44207.33688994732;44711.10577788491;45030;45050.54949450464;45409.31886021778;32579.837100815294;32658.438842782;33220.833895830365;33368.83315583504;33656.32687346288;32634;33169.50245746364;33487;33647.663523364456;33662.326753465284;36165.27669446649;36294.63705362913;36575.26849463049;36728;36796.63203367932;31474.685253147174;31569.368612628077;31613.5257971126;32138.678613213568;32349.191270218435;23021.19425820088;23392.53214935726;23443.830203365153;23578.52842943166;23735.881320593286 03:41:13 INFO - PID 28181 | |2;jquery.html;571.9542436605075;572.7966571866984;579.7651950959861;580.5631063118782;602.6835911146648;22441.663375049367;22644.54710905781;23199.884000580016;23313.883430582824;23386.532269354622;26252;26661.733382666214;26682.5997610036;26697.332566685793;26831.731682683167;6473.482121430275;6626.171728533934;6643.767468138616;6650;6651.035599838021;6578.046183303418;6597.373249541286;6882.793516194522;6905.171379434475;6916.204636466795;6900.102986611733;6954.652267386636;6955.200151982536;6963.6866341014775;6964.4428445724225;6942.479314051416;6953;6961.129858767654;6972.337627925364;6975.407090397297;6788.524803263774;6813.148356455443;6851.177858656993;6868.416184624313;6874.175098988104;29894.85052574745;32063.83968080168;32906.67093329084;33326.166845828804;33337;2000.0399808092197;2039.4595432210476;2042.60169266993;2043.0295609585476;2043.8569300148995;21395.144194232213;21694.457638559004;21706.34880953574;21724.13103475859;21731.782682173132;2171.4679903423603;2360.3273067175774;2373.181252467896;2375.596148654733;2538.0989748639154;141813;145776;146205;146536;146586.26706866504;20433.386998390073;21335.359939201848;21387.57224855494;22664.320070397913;23321.416964576023;45960.09339206138;45997.310040349425;46007.07985840264;46101.30848037282;46183;11141.164412669083;11153.38656373891;11273.94363028196;11312.377819220023;11520.251183673034;9350.766230844283;9372.39079459833;9410.811783764286;9433.386829856174;9435.6697515588;10199.54102065409;11769.52921883115;11931.821022684666;12105.213161144497;12223.572174973833;4437.667174961891;4482.641388688897;4533.909321813545;4548.545145485425;4601.907961840744;962.6149540183983;969.8690676758688;979.1334668818147;985.9408435493893;988.2933702402817;19525.707114393295;20053.498662533555;20148.29480968159;20190.798092019184;20407.387778366672;1046.895310468947;1048.533402635835;1055.7360659835042;1061.787642471509;1073.2433634287963;11152.832707509393;11332.773344533229;13132.606021819169;14084.15495070299;14533.636659083397;496744;505037;510502;518970;526746;122371;125955.37022315014;129062;129073;129678;1244.4524409259898;1261.7224210673637;1295.5077070713257;1302.6352621265871;1310.0305773727564;21182.268472007916;21327.36017919465;21426.57146857085;21461.141554338275;21859;59450;59672;60387;60939.39060609337;61215.69392153011;73690;75671.24328756642;76579.61710191413;76861;77002.6149869247;12643.304618245716;13177.868221317663;14744.55766327012;14807.629809254424;15465.613359666098;23689.28932132039;23776.64335034941;24201.636975445734;24218.152364667152;24243.151489697775;18832.811671883104;18893.527661808566;18916.432507024812;18983.525411864815;19099.14053867607;16926.238319275908;17209.967401955924;17403.912980435016;17602.647947041245;17733.91133044378;18933.526661833563;18957.336493222672;19445.222191112196;19646.41060768179;19656.80343196607;14962.700745984801;15014.849851501345;15056.774148387563;15078.170700611572;15250.847491524943;18194.90902545532;18245;18605.534861628566;18641.627167456845;19093.427197184108 03:41:13 INFO - PID 28181 | |3;mootools.html;1358.789387644915;1403.6911879386537;1415.5753274017793;1416.0441701851255;1423.928803559822;13350.465981360747;13962;13983.650408739773;13992.580222593326;14285.714285714279;14236.217008064552;14953.850461495382;14967.401303947856;14970.026948248384;15009.774853377208;2638.1426036538155;2670.6661667291587;2692.3807524269428;2693.595960605905;2696.608991696203;2321.779430954057;2371.762823717632;2374.3351861478727;2646.907358242462;2726.3456770375165;2617.515759584483;2618.698849632288;2618.8166828322023;2619.855907925073;2620.2401303622023;2597.3636459067557;2615.699194592627;2620.423506828514;2624.0422245880213;2625.934351641224;2575.163072001607;2602.0892687559394;2605.0882191232918;2607.0614578751633;2609.1129016134414;13841.377138028813;14255.78616320756;14322.928385358111;14326.64183395413;14337.71324573513;7292.67182976762;7542.321191092828;7548.8112797179965;7575.09098908129;7575.924240757632;4811.927821082687;4970.850874473772;5102.030614183293;5158.484151584849;5264.210368444726;1891.8202770736896;1913.4929243750416;1918.5491409518752;1948.3083505355678;1954.0522846419476;23327.53344933092;23402.531949361088;23570.646440303408;23615.291541253617;23755;6494.188226471691;6592.9340706593275;6609.173853268341;6779.423748981392;6827.453803695792;16276.348946042262;16279.10464924417;16288.348466061225;16302.918485407497;16307.1031093291;7399.149097853816;7629.198934111839;7637.121731001006;7638.236176382317;7640.3505702015955;6905.033295338657;6970.093887794555;7012.5792452453015;7043.68303426347;7082.079329687108;4587.977060114678;4732.503087175797;5001.674891132065;5074.847754567369;5088.643794934393;1334.3061607963805;1338.2505796753849;1352.1481466675932;1355.579770271219;1369.1579678497653;918.2241006349624;936.3960245641523;941.4539567051094;942.9764255893657;953.3279038277998;7734.57459839703;7914.525128492309;7941.880871786928;7966.203379661987;8006.199380062064;972.1493693018608;972.1493693018608;981.3032746749744;981.6073570571685;992.2855544008303;4373.890652733707;4741.952580474152;5089.814390239076;5128.461511541309;5172.224166374998;19254.903725481563;19785.901070494554;19836.50408739793;19873.205071796965;19879.60240795176;18699.90650046768;19794.40616781499;19829.702554461695;19859.702104468157;19894.30369937045;10988.670339889815;10994.725131871608;11051.17116216271;11103.222774405715;11115.55537778496;7413.629318533944;7841.255080767318;7992.880106798287;8109.1485394035135;8130.227628375532;16654.167291635127;17067.914660426617;17068.743968840725;17384.47846564605;17397.47807565775;16945.576360591083;17307.740383894;17339.306427743253;17345.306187752347;17362.826371736122;10538.631147909786;10799;10802.837957430802;10848.077913377136;10863.619773307897;11720.765584688088;12055.819162712742;13084.542041028519;13259;13260.668483287995;10687.572497100029;10723.30298530593;10879.347239165676;10882.29265097766;10915.39965301916;10454;10465.68602941913;10511.526981285708;10595.47022648849;10856.511456984612;9237.815243695222;9328.486933218466;9345.112214339626;9350.532473376168;9350.719478415658;6736.225334086643;6785.457163427012;6787.830304242236;6838.658067096526;6870.20992585859;6841.555298905555;6901.551399159039;6938.5143039986715;6992.3357281058215;7065.717371305091 03:41:13 INFO - PID 28181 | |4;prototype.html;489.48682358365164;503.90903693503566;522.5949888836154;523.3222976245761;527.259200822844;6221.564490485662;6250.937490625092;6268.216472940882;6272.215973003369;6278.623282603047;5865.1202319652075;6033.732916087627;6084.8478788030325;6086.665233412161;6093.390660933916;4940.283658869462;4949.183384741514;4950.712814668194;4962.1812400953795;4966.031623833354;4958.67768595042;4967.89506558401;4987.800487980495;4993.425756038066;4999.350084489011;5014.6991180529285;5145.922811157836;5153.948460515403;5176.482351764812;5186.222066689989;5149.510796474329;5163.941392014628;5169.147090730063;5201.349831271091;5215.16557350822;4784.5933095686905;4804.711717296974;4808.0383923215495;4820.517948205186;4839.37088178538;6047.727852246616;6048.758049678007;6069.605475644069;6092.939070609282;6135;5917.55618328627;5927.673977931212;6008.24896887889;6022.578419510636;6059.909101363483;6518.706658200393;6568.113304703852;6589.769358072493;6607.735690572371;6709.718695643504;5132.820351287724;5183.45573714762;5213.426523082458;5230.424653288135;5232.476752324776;6872.656367181671;6900.654967251618;6919.619420931846;6923;6963.025176475348;5348.1442969124755;5396.433374495621;5430.8099216527235;5500;5502.8349149525575;5479.150731636602;5481.588880833874;5493.587980901369;5554.91667624986;5558.722063896789;5949.881002379928;5971.7611295547695;5973.701314934236;5974.492168165668;5998.280206375263;6497.740090396425;6520.261490174138;6522.869542609121;6547.639879806561;6558.5409021369;1813.1025142554256;1837.5130590393555;1878.7651543557056;1895.5924476237676;1927.0557426860737;1406.7327207830683;1416.6316757643087;1428.599992002241;1456.9708605827825;1464.9267536623126;5653.23681303027;5661.094224923992;5696;5724.513416359573;5739.885202295931;6494.123293355431;6521.673916304166;6555.8361040973405;6661.833454163685;6677.632730199788;5768.711564421846;5786.305643322744;5790.971045144832;5815.941840581625;5837.3287071986415;3548.414511605608;3553.7867727936405;3562.3943929532043;3604.2971620534267;3624.2389098289655;6474.320196379406;6521.804345869631;6538.215414150332;6556;6568.67156642166;6911.274316196827;6928.1339832520935;6932.6533673316135;6990.064173047333;7012.298770122947;6467.644279564669;6527.086207930894;6555.508336874849;6569.474442044625;6572.4084832365315;6730.697118629576;6733.192016957996;6736.629485378156;6774.254831968381;6800.591964482147;6678.599284042973;6694.866102677822;6711.395974362331;6766.627835468899;6793.72825086991;6439.935600644121;6527.15147030883;6531.640759758259;6549.24683661366;6585.736570537125;4959.801607935787;5011.649184557046;5011.924821127613;5013;5023.397192336943;6806.876865317167;6814.625195614289;6818.159097043869;6832.49685069284;6835.555688880207;6435.871282574416;6496.610203387811;6523.18460192476;6529.444997175293;6553.4101930826455;6422.614643121427;6433.903491447726;6443.162388889415;6510.414062734377;6526.44525215343;6237.4426485140875;6280.466160376419;6370.973787450924;6388.316906899021;6438.259600146043;6365.172527571386;6499.38255865692;6501.6424096673245;6506.967465162644;6543.149390579194;6317.2620534599055;6325.367463253822;6325.683715814283;6327.778527751507;6328.715207815567 03:41:13 INFO - PID 28181 | |5;yui.html;917.6237742525566;922.5018490015392;931.46440796542;939.3377668743536;945.5744914788345;18867.15097820598;19713.408597742073;19750.506237344074;19788;19805.99999999998;17808.910955445237;18065;22121.557568848613;22244.443888902784;22356.88821555894;1011.4285428732779;1024.4109636958779;1025.103034844511;1025.318163421324;1025.8512515685222;1039.3140527252015;1041.2034793383068;1041.4792603698152;1041.807265655856;1042;1062.6546372428952;1088.5101704233086;1093.7648405592795;1093.8413929980147;1094.5731164845718;1091.9071878890304;1092.9180311476632;1097.3525619884267;1099.115212254135;1099.648112603967;786.1784435265162;789.4552758596608;789.9368050555942;790.3637571754736;790.8853216283665;16188.75716864248;17441.953482790945;19645.312414065582;19839;19845.106970186378;21466.57066858654;21520.139194432202;21626.35120946374;22095.558088838294;22107.668384974077;15816.051036937706;16347.591310217222;16616.335346586122;16640.750388744178;16836.07401592912;3562.3943929532043;3647.708183345326;3647.94528082079;3649.61679023704;3652.3425783358985;130429;130611;134995.32502337426;135195;135488.32255838756;16626.334946602;16629.417970370978;17129.828701712824;17359.739603905953;17366.47900562985;30848;30986.845065774527;31050.68949310478;31455.842720786248;31561.21096972594;8346.582670866432;8375.20435558621;8457.830843383097;8503.61733721984;8520.105388934073;4068.450759147477;4175.352820312856;4248.957510424918;4273.63674087706;4300.204462174445;5359.973200134053;5415.593830462731;5452.809151679751;5467.398586155519;5469.07025805614;998.7652901568125;1011.4689787861284;1033.617561502234;1036.5646428499897;1047.9161667066617;684.8596037812237;706.9678269726257;713.2974020589714;715.6743681624953;720.7297263526177;12335.876641233652;12592.307423091817;12769.808452873214;12798.296093714749;12866.356682165855;708.9184743754431;710.6624353432078;713.9107611548557;715.5527795128045;736.5433431272645;5644.625415303173;5725.914111288334;5742.7415766290615;6054.8183554493435;6343.55595108338;106702.46648766706;109185;110722;110933;112882;93563.53218233865;95617.52191238999;95986;97129.51435242778;98074;80607;80891;83000.58499707463;84637.57681211678;86248.56875715581;12453.813192801934;12655;12794.764188648858;12837.293948832903;12911.354432278536;38236.1467944938;38544.22911541697;38815;39052.609473906035;40724.1855162901;67328.20764054813;68387.65806170937;69443.65278173577;72529;72569;19196.616067678846;19719.704204437232;19782.70325945141;19784.109715063143;20048.498787529847;21302.467438314165;21865;21928;22032.33902982913;22350.329490115324;18904.810951890304;18951.810481895005;19012.239510419982;19539.609207816047;19664.41006769799;19204;19477.61044779068;19521;19719.704204436657;19901.004949752165;18060.367887123888;18086.276548938422;18176.545586360447;18446.90776546109;18812.905935470782;13668.93165534166;14182.250459521982;14422.765119957097;14692.926535367254;14877.160828921806;14319.498817541336;14370.49703260381;14631.70736585241;14805.555833325017;14985.40058397652 03:41:13 INFO - PID 28181 | __end_tp_report 03:41:13 INFO - PID 28181 | __start_cc_report 03:41:13 INFO - PID 28181 | _x_x_mozilla_cycle_collect,375 03:41:13 INFO - PID 28181 | __end_cc_report 03:41:13 INFO - PID 28181 | __startTimestamp1502275273870__endTimestamp 03:41:13 INFO - PID 28181 | 03:41:13 INFO - PID 28181 | ------- Summary: start ------- 03:41:13 INFO - PID 28181 | Number of tests: 6 03:41:13 INFO - PID 28181 | 03:41:13 INFO - PID 28181 | [#0] dojo.html Cycles:165 Average:6160.04 Median:6355.11 stddev:726.20 (11.4%) stddev-sans-first:687.45 03:41:13 INFO - PID 28181 | Values: 3094.1 3123.7 3138.3 3156.5 3196.6 6119.1 6150.1 6155.3 6170.4 6331.0 6219.7 6251.0 6266.6 6288.0 6319.7 5664.3 5725.7 5733.9 5807.1 5807.5 5571.2 5623.7 5639.0 5646.8 5716.4 5723.7 5738.5 5743.2 5758.1 5770.0 5667.7 5686.2 5760.4 5769.3 5774.4 5627.9 5629.9 5685.4 5702.0 5744.8 6048.0 6069.6 6149.1 6166.0 6222.5 6719.7 6751.2 6759.8 6761.4 6762.0 6728.1 6738.5 6766.2 6813.8 6823.9 6103.0 6237.6 6287.0 6354.0 6355.1 6866.7 6883.3 6905.0 6912.7 6916.2 6492.5 6551.5 6572.3 6573.0 6583.9 6654.5 6670.9 6688.2 6692.7 6692.8 6627.2 6654.3 6654.9 6667.9 6669.4 6667.5 6671.8 6715.1 6720.6 6773.1 5407.9 5431.2 5512.1 5524.8 5529.3 4581.9 4600.0 4611.7 4653.3 4697.2 5872.7 5883.0 5890.7 5892.9 5892.9 6758.2 6782.5 6793.2 6793.7 6806.5 5780.2 5854.9 5884.6 5890.3 5902.4 5791.7 5905.9 5909.1 6000.3 6008.1 6298.3 6302.4 6312.5 6425.9 6437.2 6543.7 6591.9 6634.1 6645.0 6658.8 6385.6 6410.6 6435.2 6450.2 6558.4 6535.8 6555.8 6564.5 6634.9 6687.6 6606.0 6683.8 6720.7 6722.1 6778.8 6459.8 6475.7 6499.6 6560.0 6573.5 6535.4 6560.7 6578.4 6588.1 6599.0 6396.0 6422.8 6513.3 6524.9 6527.8 6425.3 6560.5 6622.6 6633.8 6637.4 6273.5 6285.6 6293.5 6298.3 6346.9 03:41:13 INFO - PID 28181 | 03:41:13 INFO - PID 28181 | [#1] ext.html Cycles:180 Average:38516.99 Median:19486.02 stddev:54876.87 (281.6%) stddev-sans-first:54957.84 03:41:13 INFO - PID 28181 | Values: 828.9 835.5 861.4 882.6 894.5 43092.8 44189.6 44197.0 44230.8 44281.9 39648.8 39809.2 39901.0 40009.2 40171.2 6942.8 7043.3 7067.8 7082.3 7107.8 6818.7 6885.9 6890.9 6901.6 6902.7 7724.8 7738.3 7744.0 7745.7 7784.2 7562.8 7623.7 7649.0 7658.4 7659.4 7605.2 7746.0 7757.2 7791.5 7796.6 41083.6 41390.2 41544.8 41663.6 41736.6 3541.6 3577.0 3587.2 3595.7 3606.2 32089.4 32165.5 32180.4 32252.2 32253.2 5023.3 5093.9 5104.3 5111.9 5123.6 189844.1 191513.3 191579.0 192337.0 192773.0 50553.5 51124.0 51173.0 51201.2 51316.2 53446.7 53463.7 53475.7 53537.5 53653.7 15554.9 15991.8 15998.2 16064.2 16082.1 12828.3 12972.0 13000.4 13003.6 13017.0 14699.9 14863.5 14898.1 15051.8 15070.3 5864.7 5980.2 5990.8 6015.5 6028.1 1014.8 1015.7 1023.2 1025.0 1040.7 23093.7 23493.0 23497.8 23580.7 23605.9 1107.4 1115.4 1117.5 1121.6 1127.9 12357.8 12418.9 12467.9 12526.7 16182.9 241507.0 241731.0 242447.0 242983.0 243498.0 51366.0 52204.5 52326.7 59021.0 59206.0 1450.3 1475.2 1482.6 1498.5 1499.1 27983.6 28150.3 28351.4 28406.1 28791.3 35120.0 35151.5 35260.3 35430.5 35653.3 196415.0 196735.0 197192.0 197274.0 197560.0 33970.8 35305.6 35461.9 35517.5 35571.6 44207.3 44711.1 45030.0 45050.5 45409.3 32579.8 32658.4 33220.8 33368.8 33656.3 32634.0 33169.5 33487.0 33647.7 33662.3 36165.3 36294.6 36575.3 36728.0 36796.6 31474.7 31569.4 31613.5 32138.7 32349.2 23021.2 23392.5 23443.8 23578.5 23735.9 03:41:13 INFO - PID 28181 | 03:41:13 INFO - PID 28181 | [#2] jquery.html Cycles:180 Average:37585.33 Median:22654.43 stddev:86379.57 (381.3%) stddev-sans-first:86577.18 03:41:13 INFO - PID 28181 | Values: 572.0 572.8 579.8 580.6 602.7 22441.7 22644.5 23199.9 23313.9 23386.5 26252.0 26661.7 26682.6 26697.3 26831.7 6473.5 6626.2 6643.8 6650.0 6651.0 6578.0 6597.4 6882.8 6905.2 6916.2 6900.1 6954.7 6955.2 6963.7 6964.4 6942.5 6953.0 6961.1 6972.3 6975.4 6788.5 6813.1 6851.2 6868.4 6874.2 29894.9 32063.8 32906.7 33326.2 33337.0 2000.0 2039.5 2042.6 2043.0 2043.9 21395.1 21694.5 21706.3 21724.1 21731.8 2171.5 2360.3 2373.2 2375.6 2538.1 141813.0 145776.0 146205.0 146536.0 146586.3 20433.4 21335.4 21387.6 22664.3 23321.4 45960.1 45997.3 46007.1 46101.3 46183.0 11141.2 11153.4 11273.9 11312.4 11520.3 9350.8 9372.4 9410.8 9433.4 9435.7 10199.5 11769.5 11931.8 12105.2 12223.6 4437.7 4482.6 4533.9 4548.5 4601.9 962.6 969.9 979.1 985.9 988.3 19525.7 20053.5 20148.3 20190.8 20407.4 1046.9 1048.5 1055.7 1061.8 1073.2 11152.8 11332.8 13132.6 14084.2 14533.6 496744.0 505037.0 510502.0 518970.0 526746.0 122371.0 125955.4 129062.0 129073.0 129678.0 1244.5 1261.7 1295.5 1302.6 1310.0 21182.3 21327.4 21426.6 21461.1 21859.0 59450.0 59672.0 60387.0 60939.4 61215.7 73690.0 75671.2 76579.6 76861.0 77002.6 12643.3 13177.9 14744.6 14807.6 15465.6 23689.3 23776.6 24201.6 24218.2 24243.2 18832.8 18893.5 18916.4 18983.5 19099.1 16926.2 17210.0 17403.9 17602.6 17733.9 18933.5 18957.3 19445.2 19646.4 19656.8 14962.7 15014.8 15056.8 15078.2 15250.8 18194.9 18245.0 18605.5 18641.6 19093.4 03:41:13 INFO - PID 28181 | 03:41:13 INFO - PID 28181 | [#3] mootools.html Cycles:180 Average:8735.95 Median:2603.59 stddev:6053.36 (232.5%) stddev-sans-first:6044.97 03:41:13 INFO - PID 28181 | Values: 1358.8 1403.7 1415.6 1416.0 1423.9 13350.5 13962.0 13983.7 13992.6 14285.7 14236.2 14953.9 14967.4 14970.0 15009.8 2638.1 2670.7 2692.4 2693.6 2696.6 2321.8 2371.8 2374.3 2646.9 2726.3 2617.5 2618.7 2618.8 2619.9 2620.2 2597.4 2615.7 2620.4 2624.0 2625.9 2575.2 2602.1 2605.1 2607.1 2609.1 13841.4 14255.8 14322.9 14326.6 14337.7 7292.7 7542.3 7548.8 7575.1 7575.9 4811.9 4970.9 5102.0 5158.5 5264.2 1891.8 1913.5 1918.5 1948.3 1954.1 23327.5 23402.5 23570.6 23615.3 23755.0 6494.2 6592.9 6609.2 6779.4 6827.5 16276.3 16279.1 16288.3 16302.9 16307.1 7399.1 7629.2 7637.1 7638.2 7640.4 6905.0 6970.1 7012.6 7043.7 7082.1 4588.0 4732.5 5001.7 5074.8 5088.6 1334.3 1338.3 1352.1 1355.6 1369.2 918.2 936.4 941.5 943.0 953.3 7734.6 7914.5 7941.9 7966.2 8006.2 972.1 972.1 981.3 981.6 992.3 4373.9 4742.0 5089.8 5128.5 5172.2 19254.9 19785.9 19836.5 19873.2 19879.6 18699.9 19794.4 19829.7 19859.7 19894.3 10988.7 10994.7 11051.2 11103.2 11115.6 7413.6 7841.3 7992.9 8109.1 8130.2 16654.2 17067.9 17068.7 17384.5 17397.5 16945.6 17307.7 17339.3 17345.3 17362.8 10538.6 10799.0 10802.8 10848.1 10863.6 11720.8 12055.8 13084.5 13259.0 13260.7 10687.6 10723.3 10879.3 10882.3 10915.4 10454.0 10465.7 10511.5 10595.5 10856.5 9237.8 9328.5 9345.1 9350.5 9350.7 6736.2 6785.5 6787.8 6838.7 6870.2 6841.6 6901.6 6938.5 6992.3 7065.7 03:41:13 INFO - PID 28181 | 03:41:13 INFO - PID 28181 | [#4] prototype.html Cycles:180 Average:5584.62 Median:6085.76 stddev:1507.55 (24.8%) stddev-sans-first:1462.47 03:41:13 INFO - PID 28181 | Values: 489.5 503.9 522.6 523.3 527.3 6221.6 6250.9 6268.2 6272.2 6278.6 5865.1 6033.7 6084.8 6086.7 6093.4 4940.3 4949.2 4950.7 4962.2 4966.0 4958.7 4967.9 4987.8 4993.4 4999.4 5014.7 5145.9 5153.9 5176.5 5186.2 5149.5 5163.9 5169.1 5201.3 5215.2 4784.6 4804.7 4808.0 4820.5 4839.4 6047.7 6048.8 6069.6 6092.9 6135.0 5917.6 5927.7 6008.2 6022.6 6059.9 6518.7 6568.1 6589.8 6607.7 6709.7 5132.8 5183.5 5213.4 5230.4 5232.5 6872.7 6900.7 6919.6 6923.0 6963.0 5348.1 5396.4 5430.8 5500.0 5502.8 5479.2 5481.6 5493.6 5554.9 5558.7 5949.9 5971.8 5973.7 5974.5 5998.3 6497.7 6520.3 6522.9 6547.6 6558.5 1813.1 1837.5 1878.8 1895.6 1927.1 1406.7 1416.6 1428.6 1457.0 1464.9 5653.2 5661.1 5696.0 5724.5 5739.9 6494.1 6521.7 6555.8 6661.8 6677.6 5768.7 5786.3 5791.0 5815.9 5837.3 3548.4 3553.8 3562.4 3604.3 3624.2 6474.3 6521.8 6538.2 6556.0 6568.7 6911.3 6928.1 6932.7 6990.1 7012.3 6467.6 6527.1 6555.5 6569.5 6572.4 6730.7 6733.2 6736.6 6774.3 6800.6 6678.6 6694.9 6711.4 6766.6 6793.7 6439.9 6527.2 6531.6 6549.2 6585.7 4959.8 5011.6 5011.9 5013.0 5023.4 6806.9 6814.6 6818.2 6832.5 6835.6 6435.9 6496.6 6523.2 6529.4 6553.4 6422.6 6433.9 6443.2 6510.4 6526.4 6237.4 6280.5 6371.0 6388.3 6438.3 6365.2 6499.4 6501.6 6507.0 6543.1 6317.3 6325.4 6325.7 6327.8 6328.7 03:41:13 INFO - PID 28181 | 03:41:13 INFO - PID 28181 | [#5] yui.html Cycles:180 Average:24054.20 Median:19797.00 stddev:32448.87 (163.9%) stddev-sans-first:32493.39 03:41:13 INFO - PID 28181 | Values: 917.6 922.5 931.5 939.3 945.6 18867.2 19713.4 19750.5 19788.0 19806.0 17808.9 18065.0 22121.6 22244.4 22356.9 1011.4 1024.4 1025.1 1025.3 1025.9 1039.3 1041.2 1041.5 1041.8 1042.0 1062.7 1088.5 1093.8 1093.8 1094.6 1091.9 1092.9 1097.4 1099.1 1099.6 786.2 789.5 789.9 790.4 790.9 16188.8 17442.0 19645.3 19839.0 19845.1 21466.6 21520.1 21626.4 22095.6 22107.7 15816.1 16347.6 16616.3 16640.8 16836.1 3562.4 3647.7 3647.9 3649.6 3652.3 130429.0 130611.0 134995.3 135195.0 135488.3 16626.3 16629.4 17129.8 17359.7 17366.5 30848.0 30986.8 31050.7 31455.8 31561.2 8346.6 8375.2 8457.8 8503.6 8520.1 4068.5 4175.4 4249.0 4273.6 4300.2 5360.0 5415.6 5452.8 5467.4 5469.1 998.8 1011.5 1033.6 1036.6 1047.9 684.9 707.0 713.3 715.7 720.7 12335.9 12592.3 12769.8 12798.3 12866.4 708.9 710.7 713.9 715.6 736.5 5644.6 5725.9 5742.7 6054.8 6343.6 106702.5 109185.0 110722.0 110933.0 112882.0 93563.5 95617.5 95986.0 97129.5 98074.0 80607.0 80891.0 83000.6 84637.6 86248.6 12453.8 12655.0 12794.8 12837.3 12911.4 38236.1 38544.2 38815.0 39052.6 40724.2 67328.2 68387.7 69443.7 72529.0 72569.0 19196.6 19719.7 19782.7 19784.1 20048.5 21302.5 21865.0 21928.0 22032.3 22350.3 18904.8 18951.8 19012.2 19539.6 19664.4 19204.0 19477.6 19521.0 19719.7 19901.0 18060.4 18086.3 18176.5 18446.9 18812.9 13668.9 14182.3 14422.8 14692.9 14877.2 14319.5 14370.5 14631.7 14805.6 14985.4 03:41:13 INFO - PID 28181 | -------- Summary: end -------- 03:41:13 INFO - PID 28181 | 03:41:14 INFO - TEST-INFO | 28181: exit 0 03:41:14 INFO - TEST-OK | dromaeo_css | took 1112900ms 03:41:14 INFO - TEST-START | kraken 03:41:14 INFO - Initialising browser for kraken test... 03:41:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53339/getInfo.html -profile /tmp/tmpPEsZEO/profile 03:41:14 INFO - TEST-INFO | started process 28553 (/builds/slave/test/build/application/firefox/firefox http://localhost:53339/getInfo.html) 03:41:20 INFO - TEST-INFO | 28553: exit 0 03:41:20 INFO - Browser initialized. 03:41:20 INFO - Running cycle 1/1 for kraken test... 03:41:20 INFO - TEST-INFO | started process 28787 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPEsZEO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:21 INFO - PID 28787 | 03:41:21 INFO - PID 28787 | (/builds/slave/test/build/application/firefox/firefox:28841): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:41:21 INFO - PID 28787 | 03:41:31 INFO - PID 28787 | RSS: Main: 170840064 03:41:31 INFO - PID 28787 | 03:41:34 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53339/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:35 INFO - PID 28787 | RSS: Main: 175136768 03:41:35 INFO - PID 28787 | 03:41:38 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53339/tests/kraken/driver.html?testName=audio-dft) 03:41:38 INFO - PID 28787 | RSS: Main: 175759360 03:41:38 INFO - PID 28787 | 03:41:41 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53339/tests/kraken/driver.html?testName=audio-fft) 03:41:42 INFO - PID 28787 | RSS: Main: 170483712 03:41:42 INFO - PID 28787 | 03:41:44 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53339/tests/kraken/driver.html?testName=audio-oscillator) 03:41:45 INFO - PID 28787 | RSS: Main: 171761664 03:41:45 INFO - PID 28787 | 03:41:47 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53339/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:41:48 INFO - PID 28787 | RSS: Main: 171417600 03:41:48 INFO - PID 28787 | 03:41:51 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53339/tests/kraken/driver.html?testName=imaging-darkroom) 03:41:51 INFO - PID 28787 | RSS: Main: 173854720 03:41:51 INFO - PID 28787 | 03:41:55 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53339/tests/kraken/driver.html?testName=imaging-desaturate) 03:41:56 INFO - PID 28787 | RSS: Main: 172621824 03:41:56 INFO - PID 28787 | 03:41:59 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53339/tests/kraken/driver.html?testName=json-parse-financial) 03:41:59 INFO - PID 28787 | RSS: Main: 174088192 03:41:59 INFO - PID 28787 | 03:42:01 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53339/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:42:02 INFO - PID 28787 | RSS: Main: 174116864 03:42:02 INFO - PID 28787 | 03:42:03 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53339/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:42:04 INFO - PID 28787 | RSS: Main: 174747648 03:42:04 INFO - PID 28787 | 03:42:06 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53339/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:42:07 INFO - PID 28787 | RSS: Main: 174878720 03:42:07 INFO - PID 28787 | 03:42:10 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53339/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:42:11 INFO - PID 28787 | RSS: Main: 174772224 03:42:11 INFO - PID 28787 | 03:42:14 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53339/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:14 INFO - PID 28787 | RSS: Main: 174612480 03:42:14 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | Cycle 1(1): loaded http://localhost:53339/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53339/tests/kraken/driver.html?testName=ai-astar) 03:42:16 INFO - PID 28787 | RSS: Main: 176156672 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | __start_tp_report 03:42:16 INFO - PID 28787 | _x_x_mozilla_page_load 03:42:16 INFO - PID 28787 | _x_x_mozilla_page_load_details 03:42:16 INFO - PID 28787 | |i|pagename|runs| 03:42:16 INFO - PID 28787 | |0;ai-astar;99;107;99;107;107;109;111;99;107;110 03:42:16 INFO - PID 28787 | |1;audio-beat-detection;151;122;146;175;120;153;143;117;169;120 03:42:16 INFO - PID 28787 | |2;audio-dft;165;162;162;162;163;162;165;168;164;164 03:42:16 INFO - PID 28787 | |3;audio-fft;74;74;81;183;77;76;75;92;95;76 03:42:16 INFO - PID 28787 | |4;audio-oscillator;79;78;138;78;77;79;142;79;82;129 03:42:16 INFO - PID 28787 | |5;imaging-gaussian-blur;115;117;114;124;114;115;115;122;114;114 03:42:16 INFO - PID 28787 | |6;imaging-darkroom;150;150;158;151;150;152;152;150;151;151 03:42:16 INFO - PID 28787 | |7;imaging-desaturate;94;97;99;95;95;94;99;93;94;94 03:42:16 INFO - PID 28787 | |8;json-parse-financial;59;54;54;54;55;60;54;56;58;62 03:42:16 INFO - PID 28787 | |9;json-stringify-tinderbox;54;54;54;64;54;54;55;54;54;55 03:42:16 INFO - PID 28787 | |10;stanford-crypto-aes;87;78;80;83;86;92;77;92;85;94 03:42:16 INFO - PID 28787 | |11;stanford-crypto-ccm;138;124;134;141;125;152;130;133;146;205 03:42:16 INFO - PID 28787 | |12;stanford-crypto-pbkdf2;176;171;175;175;179;184;183;175;173;182 03:42:16 INFO - PID 28787 | |13;stanford-crypto-sha256-iterative;71;72;69;72;64;65;72;69;72;69 03:42:16 INFO - PID 28787 | __end_tp_report 03:42:16 INFO - PID 28787 | __start_cc_report 03:42:16 INFO - PID 28787 | _x_x_mozilla_cycle_collect,950 03:42:16 INFO - PID 28787 | __end_cc_report 03:42:16 INFO - PID 28787 | __startTimestamp1502275336858__endTimestamp 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | ------- Summary: start ------- 03:42:16 INFO - PID 28787 | Number of tests: 14 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#0] ai-astar Cycles:10 Average:105.50 Median:110.50 stddev:4.70 (4.3%) stddev-sans-first:4.35 03:42:16 INFO - PID 28787 | Values: 99.0 107.0 99.0 107.0 107.0 109.0 111.0 99.0 107.0 110.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:148.50 stddev:21.16 (14.2%) stddev-sans-first:22.16 03:42:16 INFO - PID 28787 | Values: 151.0 122.0 146.0 175.0 120.0 153.0 143.0 117.0 169.0 120.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#2] audio-dft Cycles:10 Average:163.70 Median:164.00 stddev:1.95 (1.2%) stddev-sans-first:2.01 03:42:16 INFO - PID 28787 | Values: 165.0 162.0 162.0 162.0 163.0 162.0 165.0 168.0 164.0 164.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#3] audio-fft Cycles:10 Average:90.30 Median:76.50 stddev:33.43 (43.7%) stddev-sans-first:34.93 03:42:16 INFO - PID 28787 | Values: 74.0 74.0 81.0 183.0 77.0 76.0 75.0 92.0 95.0 76.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:78.50 stddev:27.97 (35.6%) stddev-sans-first:28.97 03:42:16 INFO - PID 28787 | Values: 79.0 78.0 138.0 78.0 77.0 79.0 142.0 79.0 82.0 129.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:115.00 stddev:3.63 (3.2%) stddev-sans-first:3.81 03:42:16 INFO - PID 28787 | Values: 115.0 117.0 114.0 124.0 114.0 115.0 115.0 122.0 114.0 114.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#6] imaging-darkroom Cycles:10 Average:151.50 Median:151.00 stddev:2.42 (1.6%) stddev-sans-first:2.50 03:42:16 INFO - PID 28787 | Values: 150.0 150.0 158.0 151.0 150.0 152.0 152.0 150.0 151.0 151.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:2.17 (2.3%) stddev-sans-first:2.24 03:42:16 INFO - PID 28787 | Values: 94.0 97.0 99.0 95.0 95.0 94.0 99.0 93.0 94.0 94.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:2.95 (5.2%) stddev-sans-first:3.00 03:42:16 INFO - PID 28787 | Values: 59.0 54.0 54.0 54.0 55.0 60.0 54.0 56.0 58.0 62.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:54.00 stddev:3.12 (5.8%) stddev-sans-first:3.28 03:42:16 INFO - PID 28787 | Values: 54.0 54.0 54.0 64.0 54.0 54.0 55.0 54.0 54.0 55.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.50 stddev:6.00 (6.9%) stddev-sans-first:6.34 03:42:16 INFO - PID 28787 | Values: 87.0 78.0 80.0 83.0 86.0 92.0 77.0 92.0 85.0 94.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:139.50 stddev:23.56 (16.9%) stddev-sans-first:24.93 03:42:16 INFO - PID 28787 | Values: 138.0 124.0 134.0 141.0 125.0 152.0 130.0 133.0 146.0 205.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:177.50 stddev:4.45 (2.5%) stddev-sans-first:4.69 03:42:16 INFO - PID 28787 | Values: 176.0 171.0 175.0 175.0 179.0 184.0 183.0 175.0 173.0 182.0 03:42:16 INFO - PID 28787 | 03:42:16 INFO - PID 28787 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:71.50 stddev:2.95 (4.1%) stddev-sans-first:3.08 03:42:16 INFO - PID 28787 | Values: 71.0 72.0 69.0 72.0 64.0 65.0 72.0 69.0 72.0 69.0 03:42:16 INFO - PID 28787 | -------- Summary: end -------- 03:42:16 INFO - PID 28787 | 03:42:17 INFO - PID 28787 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:42:17 INFO - TEST-INFO | 28787: exit 0 03:42:17 INFO - TEST-OK | kraken | took 63003ms 03:42:17 INFO - SUITE-END | took 1175s 03:42:18 INFO - Completed test suite (00:19:36) 03:42:18 INFO - javascript benchmark 03:42:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8685.213651277023, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3094.1336425800782, 3123.687631236876, 3138.262508310548, 3156.463401221792, 3196.6164060312767, 6119.112728654348, 6150.077488376745, 6155.3229144794095, 6170.352113028135, 6331.0, 6219.689015549227, 6251.0, 6266.592671476362, 6287.968560157193, 6319.715612797424, 5664.320281566207, 5725.685087320196, 5733.942660573382, 5807.070868661015, 5807.477327040566, 5571.247881535981, 5623.718814059301, 5639.0, 5646.830595082133, 5716.428357164292, 5723.685197314146, 5738.483536481715, 5743.195952566644, 5758.078707406837, 5769.971150144243, 5667.688277144755, 5686.2039314496005, 5760.423957604248, 5769.3076830780155, 5774.422557744234, 5627.887442251173, 5629.8592535186535, 5685.403032681592, 5701.971490142523, 5744.798932037359, 6047.971844786352, 6069.6054756441135, 6149.077638354238, 6166.0, 6222.533310001768, 6719.7312107515645, 6751.189857217166, 6759.831004224885, 6761.425278851303, 6761.966190169067, 6728.091707619457, 6738.52830301879, 6766.221884483249, 6813.761518346883, 6823.93176068238, 6102.969485152635, 6237.563370564062, 6287.0, 6354.0, 6355.110284560165, 6866.656667166621, 6883.3116688330765, 6904.965475172592, 6912.723491060301, 6916.170059592881, 6492.5455218134275, 6551.508636852255, 6572.309907459744, 6573.046908198285, 6583.868322633521, 6654.50091243149, 6670.899936500957, 6688.163979502562, 6692.732290708414, 6692.799216023527, 6627.204735431779, 6654.268030516736, 6654.933450665529, 6667.899981500282, 6669.366410191121, 6667.466602671775, 6671.766488172988, 6715.093462382516, 6720.563163394364, 6773.119494465688, 5407.918881216785, 5431.2396264522995, 5512.118061110203, 5524.8342549723575, 5529.281193444826, 4581.93127103087, 4599.977000115045, 4611.700239484489, 4653.278741795028, 4697.177993851063, 5872.708004239061, 5882.970480165954, 5890.734916928663, 5892.852678683067, 5892.941070589325, 6758.155230596176, 6782.525223234425, 6793.184817821893, 6793.660316984131, 6806.489513286523, 5780.1907732917425, 5854.912176317359, 5884.6469211847425, 5890.322612899486, 5902.439268269508, 5791.710414479259, 5905.881882362329, 5909.084091965752, 6000.279966404059, 6008.128821320972, 6298.307186209513, 6302.401271879165, 6312.52656050798, 6425.871482570323, 6437.163168788027, 6543.738250469928, 6591.934080659132, 6634.137562116894, 6645.036469711961, 6658.766943156967, 6385.553011289165, 6410.583312084699, 6435.163428754232, 6450.161479007699, 6558.442532384801, 6535.803925882231, 6555.80332590023, 6564.5076619252795, 6634.933650663432, 6687.598744075176, 6605.966970165118, 6683.832904177434, 6720.663966801542, 6722.092517510074, 6778.762743303961, 6459.806205813833, 6475.740970361321, 6499.5775274607, 6560.0, 6573.474122070318, 6535.379138981789, 6560.704768285344, 6578.440832529097, 6588.110605068255, 6598.967005165136, 6396.0, 6422.775202868064, 6513.348665133449, 6524.934750652623, 6527.836804079936, 6425.325340839145, 6560.507961902781, 6622.569532980341, 6633.800985970429, 6637.402633762985, 6273.466755325849, 6285.59143655661, 6293.496520278458, 6298.338674439118, 6346.904796427965], "value": 6105.249262066014, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.8549503836831, 835.5404527509876, 861.4443683823933, 882.5587206396804, 894.4812009034761, 43092.78453607731, 44189.558104418946, 44197.0, 44230.77884610581, 44281.89295267616, 39648.801755991255, 39809.20381592366, 39901.0, 40009.19981600366, 40171.19657606846, 6942.757003504866, 7043.295670432967, 7067.787966361017, 7082.2563630818795, 7107.78676639699, 6818.727250909958, 6885.931140688579, 6890.931090689079, 6901.551399159063, 6902.723891044352, 7724.845503089935, 7738.342240909529, 7743.961280193591, 7745.728899488519, 7784.1826608206175, 7562.848743025109, 7623.6569354378635, 7648.961755191216, 7658.425618078666, 7659.387249020093, 7605.201453847321, 7746.0, 7757.224277572254, 7791.4935529190425, 7796.649150788228, 41083.58916410828, 41390.1721965562, 41544.79227603873, 41663.583364166276, 41736.582634173574, 3541.5750109986707, 3576.982115089434, 3587.1749497615433, 3595.694365978895, 3606.1886075633033, 32089.358212835847, 32165.517517237262, 32180.356392872247, 32252.193695157574, 32253.193670158198, 5023.296738456619, 5093.949060509384, 5104.336436263299, 5111.897762044739, 5123.564497017758, 189844.0507797466, 191513.25460236214, 191579.0, 192337.0, 192773.0, 50553.49446505561, 51124.0, 51173.0, 51201.23198152031, 51316.23025654618, 53446.73276633592, 53463.73268133635, 53475.663108421824, 53537.46462535403, 53653.7317313411, 15554.922225389028, 15991.840081599268, 15998.200089995686, 16064.196790160446, 16082.115483648278, 12828.294443805493, 12972.0, 13000.414981325863, 13003.609891703263, 13017.0, 14699.926500367643, 14863.479778207928, 14898.106113633217, 15051.849481505045, 15070.2669193042, 5864.70676466175, 5980.162777211194, 5990.820275391745, 6015.488683461867, 6028.125921741323, 1014.7818219082933, 1015.6851376073441, 1023.150784848574, 1024.9538770755184, 1040.7398150462384, 23093.653595196087, 23493.0, 23497.76502234956, 23580.70306133179, 23605.88197059004, 1107.379867274329, 1115.403259256294, 1117.4524483003433, 1121.5906194239208, 1127.9154063445274, 12357.75284494305, 12418.875811241953, 12467.87532124667, 12526.686832829253, 16182.919085404497, 241507.0, 241731.0, 242447.0, 242983.0, 243498.0, 51366.0, 52204.47795521996, 52326.73836630792, 59021.0, 59206.0, 1450.3255985966575, 1475.181274392701, 1482.5552334299666, 1498.4755335632442, 1499.130504307499, 27983.58024629673, 28150.2962425941, 28351.43297134087, 28406.147815565568, 28791.280217994718, 35120.0, 35151.47272790859, 35260.294794104484, 35430.46854297239, 35653.28693426169, 196415.0, 196735.0, 197192.0, 197274.0, 197560.0, 33970.83014584911, 35305.646943530235, 35461.93614191579, 35517.467237991965, 35571.64428355683, 44207.33688994732, 44711.10577788491, 45030.0, 45050.54949450464, 45409.31886021778, 32579.837100815294, 32658.438842782, 33220.833895830365, 33368.83315583504, 33656.32687346288, 32634.0, 33169.50245746364, 33487.0, 33647.663523364456, 33662.326753465284, 36165.27669446649, 36294.63705362913, 36575.26849463049, 36728.0, 36796.63203367932, 31474.685253147174, 31569.368612628077, 31613.5257971126, 32138.678613213568, 32349.191270218435, 23021.19425820088, 23392.53214935726, 23443.830203365153, 23578.52842943166, 23735.881320593286], "value": 17458.138156621753, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.9542436605075, 572.7966571866984, 579.7651950959861, 580.5631063118782, 602.6835911146648, 22441.663375049367, 22644.54710905781, 23199.884000580016, 23313.883430582824, 23386.532269354622, 26252.0, 26661.733382666214, 26682.5997610036, 26697.332566685793, 26831.731682683167, 6473.482121430275, 6626.171728533934, 6643.767468138616, 6650.0, 6651.035599838021, 6578.046183303418, 6597.373249541286, 6882.793516194522, 6905.171379434475, 6916.204636466795, 6900.102986611733, 6954.652267386636, 6955.200151982536, 6963.6866341014775, 6964.4428445724225, 6942.479314051416, 6953.0, 6961.129858767654, 6972.337627925364, 6975.407090397297, 6788.524803263774, 6813.148356455443, 6851.177858656993, 6868.416184624313, 6874.175098988104, 29894.85052574745, 32063.83968080168, 32906.67093329084, 33326.166845828804, 33337.0, 2000.0399808092197, 2039.4595432210476, 2042.60169266993, 2043.0295609585476, 2043.8569300148995, 21395.144194232213, 21694.457638559004, 21706.34880953574, 21724.13103475859, 21731.782682173132, 2171.4679903423603, 2360.3273067175774, 2373.181252467896, 2375.596148654733, 2538.0989748639154, 141813.0, 145776.0, 146205.0, 146536.0, 146586.26706866504, 20433.386998390073, 21335.359939201848, 21387.57224855494, 22664.320070397913, 23321.416964576023, 45960.09339206138, 45997.310040349425, 46007.07985840264, 46101.30848037282, 46183.0, 11141.164412669083, 11153.38656373891, 11273.94363028196, 11312.377819220023, 11520.251183673034, 9350.766230844283, 9372.39079459833, 9410.811783764286, 9433.386829856174, 9435.6697515588, 10199.54102065409, 11769.52921883115, 11931.821022684666, 12105.213161144497, 12223.572174973833, 4437.667174961891, 4482.641388688897, 4533.909321813545, 4548.545145485425, 4601.907961840744, 962.6149540183983, 969.8690676758688, 979.1334668818147, 985.9408435493893, 988.2933702402817, 19525.707114393295, 20053.498662533555, 20148.29480968159, 20190.798092019184, 20407.387778366672, 1046.895310468947, 1048.533402635835, 1055.7360659835042, 1061.787642471509, 1073.2433634287963, 11152.832707509393, 11332.773344533229, 13132.606021819169, 14084.15495070299, 14533.636659083397, 496744.0, 505037.0, 510502.0, 518970.0, 526746.0, 122371.0, 125955.37022315014, 129062.0, 129073.0, 129678.0, 1244.4524409259898, 1261.7224210673637, 1295.5077070713257, 1302.6352621265871, 1310.0305773727564, 21182.268472007916, 21327.36017919465, 21426.57146857085, 21461.141554338275, 21859.0, 59450.0, 59672.0, 60387.0, 60939.39060609337, 61215.69392153011, 73690.0, 75671.24328756642, 76579.61710191413, 76861.0, 77002.6149869247, 12643.304618245716, 13177.868221317663, 14744.55766327012, 14807.629809254424, 15465.613359666098, 23689.28932132039, 23776.64335034941, 24201.636975445734, 24218.152364667152, 24243.151489697775, 18832.811671883104, 18893.527661808566, 18916.432507024812, 18983.525411864815, 19099.14053867607, 16926.238319275908, 17209.967401955924, 17403.912980435016, 17602.647947041245, 17733.91133044378, 18933.526661833563, 18957.336493222672, 19445.222191112196, 19646.41060768179, 19656.80343196607, 14962.700745984801, 15014.849851501345, 15056.774148387563, 15078.170700611572, 15250.847491524943, 18194.90902545532, 18245.0, 18605.534861628566, 18641.627167456845, 19093.427197184108], "value": 13152.62480754781, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.789387644915, 1403.6911879386537, 1415.5753274017793, 1416.0441701851255, 1423.928803559822, 13350.465981360747, 13962.0, 13983.650408739773, 13992.580222593326, 14285.714285714279, 14236.217008064552, 14953.850461495382, 14967.401303947856, 14970.026948248384, 15009.774853377208, 2638.1426036538155, 2670.6661667291587, 2692.3807524269428, 2693.595960605905, 2696.608991696203, 2321.779430954057, 2371.762823717632, 2374.3351861478727, 2646.907358242462, 2726.3456770375165, 2617.515759584483, 2618.698849632288, 2618.8166828322023, 2619.855907925073, 2620.2401303622023, 2597.3636459067557, 2615.699194592627, 2620.423506828514, 2624.0422245880213, 2625.934351641224, 2575.163072001607, 2602.0892687559394, 2605.0882191232918, 2607.0614578751633, 2609.1129016134414, 13841.377138028813, 14255.78616320756, 14322.928385358111, 14326.64183395413, 14337.71324573513, 7292.67182976762, 7542.321191092828, 7548.8112797179965, 7575.09098908129, 7575.924240757632, 4811.927821082687, 4970.850874473772, 5102.030614183293, 5158.484151584849, 5264.210368444726, 1891.8202770736896, 1913.4929243750416, 1918.5491409518752, 1948.3083505355678, 1954.0522846419476, 23327.53344933092, 23402.531949361088, 23570.646440303408, 23615.291541253617, 23755.0, 6494.188226471691, 6592.9340706593275, 6609.173853268341, 6779.423748981392, 6827.453803695792, 16276.348946042262, 16279.10464924417, 16288.348466061225, 16302.918485407497, 16307.1031093291, 7399.149097853816, 7629.198934111839, 7637.121731001006, 7638.236176382317, 7640.3505702015955, 6905.033295338657, 6970.093887794555, 7012.5792452453015, 7043.68303426347, 7082.079329687108, 4587.977060114678, 4732.503087175797, 5001.674891132065, 5074.847754567369, 5088.643794934393, 1334.3061607963805, 1338.2505796753849, 1352.1481466675932, 1355.579770271219, 1369.1579678497653, 918.2241006349624, 936.3960245641523, 941.4539567051094, 942.9764255893657, 953.3279038277998, 7734.57459839703, 7914.525128492309, 7941.880871786928, 7966.203379661987, 8006.199380062064, 972.1493693018608, 972.1493693018608, 981.3032746749744, 981.6073570571685, 992.2855544008303, 4373.890652733707, 4741.952580474152, 5089.814390239076, 5128.461511541309, 5172.224166374998, 19254.903725481563, 19785.901070494554, 19836.50408739793, 19873.205071796965, 19879.60240795176, 18699.90650046768, 19794.40616781499, 19829.702554461695, 19859.702104468157, 19894.30369937045, 10988.670339889815, 10994.725131871608, 11051.17116216271, 11103.222774405715, 11115.55537778496, 7413.629318533944, 7841.255080767318, 7992.880106798287, 8109.1485394035135, 8130.227628375532, 16654.167291635127, 17067.914660426617, 17068.743968840725, 17384.47846564605, 17397.47807565775, 16945.576360591083, 17307.740383894, 17339.306427743253, 17345.306187752347, 17362.826371736122, 10538.631147909786, 10799.0, 10802.837957430802, 10848.077913377136, 10863.619773307897, 11720.765584688088, 12055.819162712742, 13084.542041028519, 13259.0, 13260.668483287995, 10687.572497100029, 10723.30298530593, 10879.347239165676, 10882.29265097766, 10915.39965301916, 10454.0, 10465.68602941913, 10511.526981285708, 10595.47022648849, 10856.511456984612, 9237.815243695222, 9328.486933218466, 9345.112214339626, 9350.532473376168, 9350.719478415658, 6736.225334086643, 6785.457163427012, 6787.830304242236, 6838.658067096526, 6870.20992585859, 6841.555298905555, 6901.551399159039, 6938.5143039986715, 6992.3357281058215, 7065.717371305091], "value": 6348.664303464959, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.48682358365164, 503.90903693503566, 522.5949888836154, 523.3222976245761, 527.259200822844, 6221.564490485662, 6250.937490625092, 6268.216472940882, 6272.215973003369, 6278.623282603047, 5865.1202319652075, 6033.732916087627, 6084.8478788030325, 6086.665233412161, 6093.390660933916, 4940.283658869462, 4949.183384741514, 4950.712814668194, 4962.1812400953795, 4966.031623833354, 4958.67768595042, 4967.89506558401, 4987.800487980495, 4993.425756038066, 4999.350084489011, 5014.6991180529285, 5145.922811157836, 5153.948460515403, 5176.482351764812, 5186.222066689989, 5149.510796474329, 5163.941392014628, 5169.147090730063, 5201.349831271091, 5215.16557350822, 4784.5933095686905, 4804.711717296974, 4808.0383923215495, 4820.517948205186, 4839.37088178538, 6047.727852246616, 6048.758049678007, 6069.605475644069, 6092.939070609282, 6135.0, 5917.55618328627, 5927.673977931212, 6008.24896887889, 6022.578419510636, 6059.909101363483, 6518.706658200393, 6568.113304703852, 6589.769358072493, 6607.735690572371, 6709.718695643504, 5132.820351287724, 5183.45573714762, 5213.426523082458, 5230.424653288135, 5232.476752324776, 6872.656367181671, 6900.654967251618, 6919.619420931846, 6923.0, 6963.025176475348, 5348.1442969124755, 5396.433374495621, 5430.8099216527235, 5500.0, 5502.8349149525575, 5479.150731636602, 5481.588880833874, 5493.587980901369, 5554.91667624986, 5558.722063896789, 5949.881002379928, 5971.7611295547695, 5973.701314934236, 5974.492168165668, 5998.280206375263, 6497.740090396425, 6520.261490174138, 6522.869542609121, 6547.639879806561, 6558.5409021369, 1813.1025142554256, 1837.5130590393555, 1878.7651543557056, 1895.5924476237676, 1927.0557426860737, 1406.7327207830683, 1416.6316757643087, 1428.599992002241, 1456.9708605827825, 1464.9267536623126, 5653.23681303027, 5661.094224923992, 5696.0, 5724.513416359573, 5739.885202295931, 6494.123293355431, 6521.673916304166, 6555.8361040973405, 6661.833454163685, 6677.632730199788, 5768.711564421846, 5786.305643322744, 5790.971045144832, 5815.941840581625, 5837.3287071986415, 3548.414511605608, 3553.7867727936405, 3562.3943929532043, 3604.2971620534267, 3624.2389098289655, 6474.320196379406, 6521.804345869631, 6538.215414150332, 6556.0, 6568.67156642166, 6911.274316196827, 6928.1339832520935, 6932.6533673316135, 6990.064173047333, 7012.298770122947, 6467.644279564669, 6527.086207930894, 6555.508336874849, 6569.474442044625, 6572.4084832365315, 6730.697118629576, 6733.192016957996, 6736.629485378156, 6774.254831968381, 6800.591964482147, 6678.599284042973, 6694.866102677822, 6711.395974362331, 6766.627835468899, 6793.72825086991, 6439.935600644121, 6527.15147030883, 6531.640759758259, 6549.24683661366, 6585.736570537125, 4959.801607935787, 5011.649184557046, 5011.924821127613, 5013.0, 5023.397192336943, 6806.876865317167, 6814.625195614289, 6818.159097043869, 6832.49685069284, 6835.555688880207, 6435.871282574416, 6496.610203387811, 6523.18460192476, 6529.444997175293, 6553.4101930826455, 6422.614643121427, 6433.903491447726, 6443.162388889415, 6510.414062734377, 6526.44525215343, 6237.4426485140875, 6280.466160376419, 6370.973787450924, 6388.316906899021, 6438.259600146043, 6365.172527571386, 6499.38255865692, 6501.6424096673245, 6506.967465162644, 6543.149390579194, 6317.2620534599055, 6325.367463253822, 6325.683715814283, 6327.778527751507, 6328.715207815567], "value": 5151.269953737127, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [917.6237742525566, 922.5018490015392, 931.46440796542, 939.3377668743536, 945.5744914788345, 18867.15097820598, 19713.408597742073, 19750.506237344074, 19788.0, 19805.99999999998, 17808.910955445237, 18065.0, 22121.557568848613, 22244.443888902784, 22356.88821555894, 1011.4285428732779, 1024.4109636958779, 1025.103034844511, 1025.318163421324, 1025.8512515685222, 1039.3140527252015, 1041.2034793383068, 1041.4792603698152, 1041.807265655856, 1042.0, 1062.6546372428952, 1088.5101704233086, 1093.7648405592795, 1093.8413929980147, 1094.5731164845718, 1091.9071878890304, 1092.9180311476632, 1097.3525619884267, 1099.115212254135, 1099.648112603967, 786.1784435265162, 789.4552758596608, 789.9368050555942, 790.3637571754736, 790.8853216283665, 16188.75716864248, 17441.953482790945, 19645.312414065582, 19839.0, 19845.106970186378, 21466.57066858654, 21520.139194432202, 21626.35120946374, 22095.558088838294, 22107.668384974077, 15816.051036937706, 16347.591310217222, 16616.335346586122, 16640.750388744178, 16836.07401592912, 3562.3943929532043, 3647.708183345326, 3647.94528082079, 3649.61679023704, 3652.3425783358985, 130429.0, 130611.0, 134995.32502337426, 135195.0, 135488.32255838756, 16626.334946602, 16629.417970370978, 17129.828701712824, 17359.739603905953, 17366.47900562985, 30848.0, 30986.845065774527, 31050.68949310478, 31455.842720786248, 31561.21096972594, 8346.582670866432, 8375.20435558621, 8457.830843383097, 8503.61733721984, 8520.105388934073, 4068.450759147477, 4175.352820312856, 4248.957510424918, 4273.63674087706, 4300.204462174445, 5359.973200134053, 5415.593830462731, 5452.809151679751, 5467.398586155519, 5469.07025805614, 998.7652901568125, 1011.4689787861284, 1033.617561502234, 1036.5646428499897, 1047.9161667066617, 684.8596037812237, 706.9678269726257, 713.2974020589714, 715.6743681624953, 720.7297263526177, 12335.876641233652, 12592.307423091817, 12769.808452873214, 12798.296093714749, 12866.356682165855, 708.9184743754431, 710.6624353432078, 713.9107611548557, 715.5527795128045, 736.5433431272645, 5644.625415303173, 5725.914111288334, 5742.7415766290615, 6054.8183554493435, 6343.55595108338, 106702.46648766706, 109185.0, 110722.0, 110933.0, 112882.0, 93563.53218233865, 95617.52191238999, 95986.0, 97129.51435242778, 98074.0, 80607.0, 80891.0, 83000.58499707463, 84637.57681211678, 86248.56875715581, 12453.813192801934, 12655.0, 12794.764188648858, 12837.293948832903, 12911.354432278536, 38236.1467944938, 38544.22911541697, 38815.0, 39052.609473906035, 40724.1855162901, 67328.20764054813, 68387.65806170937, 69443.65278173577, 72529.0, 72569.0, 19196.616067678846, 19719.704204437232, 19782.70325945141, 19784.109715063143, 20048.498787529847, 21302.467438314165, 21865.0, 21928.0, 22032.33902982913, 22350.329490115324, 18904.810951890304, 18951.810481895005, 19012.239510419982, 19539.609207816047, 19664.41006769799, 19204.0, 19477.61044779068, 19521.0, 19719.704204436657, 19901.004949752165, 18060.367887123888, 18086.276548938422, 18176.545586360447, 18446.90776546109, 18812.905935470782, 13668.93165534166, 14182.250459521982, 14422.765119957097, 14692.926535367254, 14877.160828921806, 14319.498817541336, 14370.49703260381, 14631.70736585241, 14805.555833325017, 14985.40058397652], "value": 9361.47968793488, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1547.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 99.0, 107.0, 107.0, 109.0, 111.0, 99.0, 107.0, 110.0], "value": 105.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 122.0, 146.0, 175.0, 120.0, 153.0, 143.0, 117.0, 169.0, 120.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 162.0, 162.0, 163.0, 162.0, 165.0, 168.0, 164.0, 164.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 81.0, 183.0, 77.0, 76.0, 75.0, 92.0, 95.0, 76.0], "value": 90.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 138.0, 78.0, 77.0, 79.0, 142.0, 79.0, 82.0, 129.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 114.0, 124.0, 114.0, 115.0, 115.0, 122.0, 114.0, 114.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 158.0, 151.0, 150.0, 152.0, 152.0, 150.0, 151.0, 151.0], "value": 151.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 99.0, 95.0, 95.0, 94.0, 99.0, 93.0, 94.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 54.0, 54.0, 54.0, 55.0, 60.0, 54.0, 56.0, 58.0, 62.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 64.0, 54.0, 54.0, 55.0, 54.0, 54.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 78.0, 80.0, 83.0, 86.0, 92.0, 77.0, 92.0, 85.0, 94.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 124.0, 134.0, 141.0, 125.0, 152.0, 130.0, 133.0, 146.0, 205.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 171.0, 175.0, 175.0, 179.0, 184.0, 183.0, 175.0, 173.0, 182.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 69.0, 72.0, 64.0, 65.0, 72.0, 69.0, 72.0, 69.0], "value": 69.5, "unit": "score"}]}]} 03:42:18 INFO - Return code: 0 03:42:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:42:18 INFO - # TBPL SUCCESS # 03:42:18 INFO - Running post-action listener: _package_coverage_data 03:42:18 INFO - Running post-action listener: _resource_record_post_action 03:42:18 INFO - [mozharness: 2017-08-09 10:42:18.142492Z] Finished run-tests step (success) 03:42:18 INFO - Running post-run listener: _resource_record_post_run 03:42:18 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 12288; Write bytes: 433659904; Read time: 76; Write time: 327328 03:42:18 INFO - TinderboxPrint: CPU usage
13.2% 03:42:18 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 76 03:42:18 INFO - TinderboxPrint: I/O write bytes / time
433,659,904 / 327,328 03:42:18 INFO - TinderboxPrint: CPU idle
8,271.7 (86.8%) 03:42:18 INFO - TinderboxPrint: CPU user
1,206.5 (12.7%) 03:42:18 INFO - TinderboxPrint: Swap in / out
0 / 0 03:42:18 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 107872256; Read time: 56; Write time: 94812 03:42:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:42:18 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 325787648; Read time: 4; Write time: 232516 03:42:18 INFO - Running post-run listener: _upload_blobber_files 03:42:18 INFO - Blob upload gear active. 03:42:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:42:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:42:19 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:42:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:42:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:25 INFO - (blobuploader) - INFO - Done attempting. 03:42:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:42:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:42:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:26 INFO - (blobuploader) - INFO - Done attempting. 03:42:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:42:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:42:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:27 INFO - (blobuploader) - INFO - Done attempting. 03:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:42:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:28 INFO - (blobuploader) - INFO - Done attempting. 03:42:28 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:28 INFO - Return code: 0 03:42:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612c32c516652d699074d521dbf7d6d691a1d99e9445268673897d18ddfb35aa8e3911b963881df7df80dc583890749845b65c702b59a5c0240c579fc399b0a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1edf70e4095c980d9c5758858763f38ee02f02038990e0e3c93c1e0d6865c201793c9c5828156ec83b4c5e943eb7f52395b2353eb0c9fa2840c7df5a8b696c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e9b0ee8e99233cb0e689364ac3ea211aa333a3af1e2b5fa768a66a874197309cf1fc2d2696d6b5fdc8e7e9b3661d0176d3faefa90968d52541cbb772eddd0e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7fe95144f263011d0f9b39c854e02daab8884884c39d654092bb5015bec39f83f5da998b5073e0bb55774c2bd88741d0ade6329c9cfb4b18c9cfe2152607cc8"} 03:42:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:28 INFO - Contents: 03:42:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612c32c516652d699074d521dbf7d6d691a1d99e9445268673897d18ddfb35aa8e3911b963881df7df80dc583890749845b65c702b59a5c0240c579fc399b0a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1edf70e4095c980d9c5758858763f38ee02f02038990e0e3c93c1e0d6865c201793c9c5828156ec83b4c5e943eb7f52395b2353eb0c9fa2840c7df5a8b696c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e9b0ee8e99233cb0e689364ac3ea211aa333a3af1e2b5fa768a66a874197309cf1fc2d2696d6b5fdc8e7e9b3661d0176d3faefa90968d52541cbb772eddd0e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7fe95144f263011d0f9b39c854e02daab8884884c39d654092bb5015bec39f83f5da998b5073e0bb55774c2bd88741d0ade6329c9cfb4b18c9cfe2152607cc8"} 03:42:28 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:28 INFO - Copying logs to upload dir... 03:42:28 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.912495 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-09 03:42:27.555711) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-09 03:42:27.564437) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612c32c516652d699074d521dbf7d6d691a1d99e9445268673897d18ddfb35aa8e3911b963881df7df80dc583890749845b65c702b59a5c0240c579fc399b0a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1edf70e4095c980d9c5758858763f38ee02f02038990e0e3c93c1e0d6865c201793c9c5828156ec83b4c5e943eb7f52395b2353eb0c9fa2840c7df5a8b696c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e9b0ee8e99233cb0e689364ac3ea211aa333a3af1e2b5fa768a66a874197309cf1fc2d2696d6b5fdc8e7e9b3661d0176d3faefa90968d52541cbb772eddd0e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7fe95144f263011d0f9b39c854e02daab8884884c39d654092bb5015bec39f83f5da998b5073e0bb55774c2bd88741d0ade6329c9cfb4b18c9cfe2152607cc8"} build_url:https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/612c32c516652d699074d521dbf7d6d691a1d99e9445268673897d18ddfb35aa8e3911b963881df7df80dc583890749845b65c702b59a5c0240c579fc399b0a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1edf70e4095c980d9c5758858763f38ee02f02038990e0e3c93c1e0d6865c201793c9c5828156ec83b4c5e943eb7f52395b2353eb0c9fa2840c7df5a8b696c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e9b0ee8e99233cb0e689364ac3ea211aa333a3af1e2b5fa768a66a874197309cf1fc2d2696d6b5fdc8e7e9b3661d0176d3faefa90968d52541cbb772eddd0e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7fe95144f263011d0f9b39c854e02daab8884884c39d654092bb5015bec39f83f5da998b5073e0bb55774c2bd88741d0ade6329c9cfb4b18c9cfe2152607cc8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-09 03:42:27.588860) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:42:27.593205) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1502270737.419972-614188685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:42:27.670981) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-09 03:42:27.673313) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-09 03:42:27.673626) ========= ========= Total master_lag: 0.23 =========