builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-066 starttime: 1500218070.6 results: success (0) revision: bc3f73d54feeaf0e97b30496574357f05b2d5307 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:30.597957) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:30.598510) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:30.598877) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:30.616511) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:30.617191) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-16 08:14:32-- 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% 213M=0s 2017-07-16 08:14:32 (213 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128887 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:30.764745) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:30.765514) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019937 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:30.815497) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:30.815922) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bc3f73d54feeaf0e97b30496574357f05b2d5307 --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 bc3f73d54feeaf0e97b30496574357f05b2d5307 --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=82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-16 08:14:32,345 truncating revision to first 12 chars 2017-07-16 08:14:32,345 Setting DEBUG logging. 2017-07-16 08:14:32,345 attempt 1/10 2017-07-16 08:14:32,345 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bc3f73d54fee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-16 08:14:32,646 unpacking tar archive at: mozilla-central-bc3f73d54fee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.608022 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:31.437833) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:31.438168) ========= script_repo_revision: bc3f73d54feeaf0e97b30496574357f05b2d5307 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:31.438594) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:31.438925) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-16 08:14:31.448876) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-07-16 08:14:31.449255) ========= /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=82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:14:33 INFO - MultiFileLogger online at 20170716 08:14:33 in /builds/slave/test 08:14:33 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 08:14:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:14:33 INFO - {'append_to_log': False, 08:14:33 INFO - 'base_work_dir': '/builds/slave/test', 08:14:33 INFO - 'blob_upload_branch': 'Firefox', 08:14:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:14:33 INFO - 'branch': 'Firefox', 08:14:33 INFO - 'buildbot_json_path': 'buildprops.json', 08:14:33 INFO - 'code_coverage': False, 08:14:33 INFO - 'config_files': ('talos/linux_config.py',), 08:14:33 INFO - 'default_actions': ('clobber', 08:14:33 INFO - 'read-buildbot-config', 08:14:33 INFO - 'download-and-extract', 08:14:33 INFO - 'populate-webroot', 08:14:33 INFO - 'create-virtualenv', 08:14:33 INFO - 'install', 08:14:33 INFO - 'setup-mitmproxy', 08:14:33 INFO - 'run-tests'), 08:14:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:14:33 INFO - 'disable_ccov_upload': False, 08:14:33 INFO - 'download_minidump_stackwalk': True, 08:14:33 INFO - 'download_symbols': 'ondemand', 08:14:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:14:33 INFO - 'tooltool.py': '/tools/tooltool.py', 08:14:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:14:33 INFO - '/tools/misc-python/virtualenv.py')}, 08:14:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:14:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:14:33 INFO - 'gecko_profile': False, 08:14:33 INFO - 'gecko_profile_interval': 0, 08:14:33 INFO - 'installer_path': 'installer.exe', 08:14:33 INFO - 'log_level': 'info', 08:14:33 INFO - 'log_name': 'talos', 08:14:33 INFO - 'log_to_console': True, 08:14:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:14:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:14:33 INFO - 'opt_config_files': (), 08:14:33 INFO - 'pip_index': False, 08:14:33 INFO - 'suite': 'dromaeojs-e10s', 08:14:33 INFO - 'system_bits': '32', 08:14:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:14:33 INFO - 'title': 'talos-linux64-ix-066', 08:14:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:14:33 INFO - 'use_talos_json': True, 08:14:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:14:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:14:33 INFO - 'work_dir': 'build'} 08:14:33 INFO - [mozharness: 2017-07-16 15:14:33.077755Z] Running clobber step. 08:14:33 INFO - Running pre-action listener: _resource_record_pre_action 08:14:33 INFO - Running main action method: clobber 08:14:33 INFO - rmtree: /builds/slave/test/build 08:14:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:14:34 INFO - Running post-action listener: _resource_record_post_action 08:14:34 INFO - [mozharness: 2017-07-16 15:14:34.640383Z] Finished clobber step (success) 08:14:34 INFO - [mozharness: 2017-07-16 15:14:34.640485Z] Running read-buildbot-config step. 08:14:34 INFO - Running pre-action listener: _resource_record_pre_action 08:14:34 INFO - Running main action method: read_buildbot_config 08:14:34 INFO - Using buildbot properties: 08:14:34 INFO - { 08:14:34 INFO - "project": "", 08:14:34 INFO - "product": "firefox", 08:14:34 INFO - "who": "archaeopteryx@coole-files.de", 08:14:34 INFO - "installer_path": "public/build/target.tar.bz2", 08:14:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 08:14:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:14:34 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 08:14:34 INFO - "stage_platform": "linux64-stylosequential", 08:14:34 INFO - "basedir": "/builds/slave/test", 08:14:34 INFO - "buildnumber": 124, 08:14:34 INFO - "platform": "ubuntu64_hw_styloseq", 08:14:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:14:34 INFO - "slavebuilddir": "test", 08:14:34 INFO - "taskId": "GzjaXh_yRzibtwOp3D4kQw", 08:14:34 INFO - "branch": "mozilla-central", 08:14:34 INFO - "script_repo_revision": "production", 08:14:34 INFO - "revision": "bc3f73d54feeaf0e97b30496574357f05b2d5307", 08:14:34 INFO - "slavename": "talos-linux64-ix-066", 08:14:34 INFO - "repo_path": "mozilla-central" 08:14:34 INFO - } 08:14:34 INFO - Finding installer, test and symbols from parent task. 08:14:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GzjaXh_yRzibtwOp3D4kQw'}, attempt #1 08:14:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GzjaXh_yRzibtwOp3D4kQw'}, attempt #1 08:14:35 INFO - Task dependencies: KcZaFrWRQQ6ypWymSEoK1w 08:14:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w'}, attempt #1 08:14:36 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.tar.bz2 08:14:36 INFO - Set test_url: https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/test_packages.json 08:14:36 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.crashreporter-symbols.zip 08:14:36 INFO - Running post-action listener: _resource_record_post_action 08:14:36 INFO - [mozharness: 2017-07-16 15:14:36.008102Z] Finished read-buildbot-config step (success) 08:14:36 INFO - [mozharness: 2017-07-16 15:14:36.008324Z] Running download-and-extract step. 08:14:36 INFO - Running pre-action listener: _resource_record_pre_action 08:14:36 INFO - Running main action method: download_and_extract 08:14:36 INFO - mkdir: /builds/slave/test/build/tests 08:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:36 INFO - trying https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.test_packages.json 08:14:36 INFO - Downloading https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:14:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:14:36 INFO - Downloaded 989 bytes. 08:14:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:14:36 INFO - Using the following test package requirements: 08:14:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:14:36 INFO - u'common': [u'target.common.tests.zip'], 08:14:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:14:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:14:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:14:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:14:36 INFO - u'mozbase': [u'target.common.tests.zip'], 08:14:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:14:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:14:36 INFO - u'web-platform': [u'target.common.tests.zip', 08:14:36 INFO - u'target.web-platform.tests.tar.gz'], 08:14:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:14:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:14:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.common.tests.zip 08:14:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:14:36 INFO - Fetch https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.common.tests.zip into memory 08:14:38 INFO - Content-Length response header: 72753072 08:14:38 INFO - Bytes received: 72753072 08:14:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:14:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.common.tests.zip 08:14:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:14:49 INFO - Fetch https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.common.tests.zip into memory 08:14:51 INFO - Content-Length response header: 72753072 08:14:51 INFO - Bytes received: 72753072 08:15:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.talos.tests.zip 08:15:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:15:03 INFO - Fetch https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.talos.tests.zip into memory 08:15:04 INFO - Content-Length response header: 14495033 08:15:04 INFO - Bytes received: 14495033 08:15:04 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')]} 08:15:04 INFO - trying https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.tar.bz2 08:15:04 INFO - Downloading https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:15:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:15:07 INFO - Downloaded 61922932 bytes. 08:15:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.tar.bz2 08:15:07 INFO - mkdir: /builds/slave/test/properties 08:15:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:15:07 INFO - Writing to file /builds/slave/test/properties/build_url 08:15:07 INFO - Contents: 08:15:07 INFO - build_url:https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.tar.bz2 08:15:07 INFO - Running post-action listener: _resource_record_post_action 08:15:07 INFO - Running post-action listener: set_extra_try_arguments 08:15:07 INFO - [mozharness: 2017-07-16 15:15:07.100702Z] Finished download-and-extract step (success) 08:15:07 INFO - [mozharness: 2017-07-16 15:15:07.100933Z] Running populate-webroot step. 08:15:07 INFO - Running pre-action listener: _resource_record_pre_action 08:15:07 INFO - Running main action method: populate_webroot 08:15:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:15:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:15:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:15:07 INFO - '--tppagecycles', 08:15:07 INFO - '7'], 08:15:07 INFO - 'tests': ['tsvgm']}}, 08:15:07 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:15:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:15:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:15:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:15:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:15:07 INFO - 'talos_options': ['--disable-e10s'], 08:15:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:15:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:15:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:15:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:15:07 INFO - 'talos_options': ['--disable-e10s'], 08:15:07 INFO - 'tests': ['damp', 'tps']}, 08:15:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:15:07 INFO - 'tests': ['damp', 'tps']}, 08:15:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:15:07 INFO - 'tests': ['dromaeo_dom']}, 08:15:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:15:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:15:07 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:15:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:15:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:15:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:15:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:15:07 INFO - 'tests': ['a11yr', 08:15:07 INFO - 'ts_paint', 08:15:07 INFO - 'tpaint', 08:15:07 INFO - 'sessionrestore', 08:15:07 INFO - 'sessionrestore_no_auto_restore', 08:15:07 INFO - 'tabpaint']}, 08:15:07 INFO - 'other-e10s': {'tests': ['a11yr', 08:15:07 INFO - 'ts_paint', 08:15:07 INFO - 'tpaint', 08:15:07 INFO - 'sessionrestore', 08:15:07 INFO - 'sessionrestore_many_windows', 08:15:07 INFO - 'sessionrestore_no_auto_restore', 08:15:07 INFO - 'tabpaint']}, 08:15:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:15:07 INFO - 'ts_paint', 08:15:07 INFO - 'tpaint', 08:15:07 INFO - 'sessionrestore', 08:15:07 INFO - 'sessionrestore_many_windows', 08:15:07 INFO - 'sessionrestore_no_auto_restore', 08:15:07 INFO - 'tabpaint']}, 08:15:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:15:07 INFO - 'ts_paint', 08:15:07 INFO - 'tpaint', 08:15:07 INFO - 'sessionrestore', 08:15:07 INFO - 'sessionrestore_many_windows', 08:15:07 INFO - 'sessionrestore_no_auto_restore', 08:15:07 INFO - 'tabpaint']}, 08:15:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:15:07 INFO - 'tests': ['a11yr', 08:15:07 INFO - 'ts_paint', 08:15:07 INFO - 'tpaint', 08:15:07 INFO - 'sessionrestore', 08:15:07 INFO - 'sessionrestore_no_auto_restore', 08:15:07 INFO - 'tabpaint']}, 08:15:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:15:07 INFO - 'tests': ['a11yr', 08:15:07 INFO - 'ts_paint', 08:15:07 INFO - 'tpaint', 08:15:07 INFO - 'sessionrestore', 08:15:07 INFO - 'sessionrestore_no_auto_restore', 08:15:07 INFO - 'tabpaint']}, 08:15:07 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 08:15:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:15:07 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 08:15:07 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:15:07 INFO - 'talos_options': ['--mitmproxy', 08:15:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:15:07 INFO - '--firstNonBlankPaint'], 08:15:07 INFO - 'tests': ['quantum_pageload_google', 08:15:07 INFO - 'quantum_pageload_youtube', 08:15:07 INFO - 'quantum_pageload_amazon', 08:15:07 INFO - 'quantum_pageload_facebook']}, 08:15:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:15:07 INFO - 'tests': ['tsvgx', 08:15:07 INFO - 'tsvgr_opacity', 08:15:07 INFO - 'tart', 08:15:07 INFO - 'tscrollx', 08:15:07 INFO - 'cart', 08:15:07 INFO - 'tsvg_static']}, 08:15:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:15:07 INFO - 'tsvgr_opacity', 08:15:07 INFO - 'tart', 08:15:07 INFO - 'tscrollx', 08:15:07 INFO - 'cart', 08:15:07 INFO - 'tsvg_static']}, 08:15:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:15:07 INFO - 'talos_options': ['--disable-e10s'], 08:15:07 INFO - 'tests': ['tp5o']}, 08:15:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:15:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:15:07 INFO - 'talos_options': ['--disable-e10s', 08:15:07 INFO - '--xperf_path', 08:15:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:15:07 INFO - 'tests': ['tp5n']}, 08:15:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:15:07 INFO - 'talos_options': ['--xperf_path', 08:15:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:15:07 INFO - 'tests': ['tp5n']}}, 08:15:07 INFO - 'talos.zip': {'path': '', 08:15:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:15:07 INFO - Running post-action listener: _resource_record_post_action 08:15:07 INFO - [mozharness: 2017-07-16 15:15:07.124554Z] Finished populate-webroot step (success) 08:15:07 INFO - [mozharness: 2017-07-16 15:15:07.124645Z] Running create-virtualenv step. 08:15:07 INFO - Running pre-action listener: _resource_record_pre_action 08:15:07 INFO - Running main action method: create_virtualenv 08:15:07 INFO - Creating virtualenv /builds/slave/test/build/venv 08:15:07 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 08:15:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:15:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:15:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:15:07 INFO - Using real prefix '/usr' 08:15:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:15:07 INFO - Installing distribute.............................................................................................................................................................................................done. 08:15:09 INFO - Installing pip.................done. 08:15:09 INFO - Return code: 0 08:15:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:15:09 INFO - Reading from file tmpfile_stdout 08:15:09 INFO - Output received: 08:15:09 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) 08:15:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:15: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')]} 08:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15: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')]} 08:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1111b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, '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': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', '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 08:15: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', 'pip>=1.5'] in /builds/slave/test/build 08:15: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 pip>=1.5 08:15:09 INFO - Using env: {'DISPLAY': ':0', 08:15:09 INFO - 'HOME': '/home/cltbld', 08:15:09 INFO - 'LANG': 'en_US.UTF-8', 08:15:09 INFO - 'LANGUAGE': 'en_US:en', 08:15:09 INFO - 'LOGNAME': 'cltbld', 08:15:09 INFO - 'MAIL': '/var/mail/cltbld', 08:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:09 INFO - 'MOZ_NO_REMOTE': '1', 08:15:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:09 INFO - 'NO_EM_RESTART': '1', 08:15:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:09 INFO - 'PWD': '/builds/slave/test', 08:15:09 INFO - 'SHELL': '/bin/bash', 08:15:09 INFO - 'SHLVL': '1', 08:15:09 INFO - 'TERM': 'linux', 08:15:09 INFO - 'TMOUT': '86400', 08:15:09 INFO - 'USER': 'cltbld', 08:15:09 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:09 INFO - '_': '/tools/buildbot/bin/python'} 08:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:10 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 08:15:10 INFO - Cleaning up... 08:15:10 INFO - Return code: 0 08:15:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:10 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 0xda01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1111b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, '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': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', '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 08:15:10 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 08:15:10 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 08:15:10 INFO - Using env: {'DISPLAY': ':0', 08:15:10 INFO - 'HOME': '/home/cltbld', 08:15:10 INFO - 'LANG': 'en_US.UTF-8', 08:15:10 INFO - 'LANGUAGE': 'en_US:en', 08:15:10 INFO - 'LOGNAME': 'cltbld', 08:15:10 INFO - 'MAIL': '/var/mail/cltbld', 08:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:10 INFO - 'MOZ_NO_REMOTE': '1', 08:15:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:10 INFO - 'NO_EM_RESTART': '1', 08:15:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:10 INFO - 'PWD': '/builds/slave/test', 08:15:10 INFO - 'SHELL': '/bin/bash', 08:15:10 INFO - 'SHLVL': '1', 08:15:10 INFO - 'TERM': 'linux', 08:15:10 INFO - 'TMOUT': '86400', 08:15:10 INFO - 'USER': 'cltbld', 08:15:10 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:10 INFO - '_': '/tools/buildbot/bin/python'} 08:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:10 INFO - Downloading/unpacking psutil>=3.1.1 08:15: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 08:15: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 08:15: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 08:15: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 08:15:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:15:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:15:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:15:11 INFO - Installing collected packages: psutil 08:15:11 INFO - Running setup.py install for psutil 08:15:11 INFO - building 'psutil._psutil_linux' extension 08:15:11 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 08:15:11 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 08:15:11 INFO - building 'psutil._psutil_posix' extension 08:15:11 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 08:15:11 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 08:15:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:15:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:15:12 INFO - Successfully installed psutil 08:15:12 INFO - Cleaning up... 08:15:12 INFO - Return code: 0 08:15:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:15:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:12 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 0xda01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1111b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, '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': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', '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 08:15:12 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 08:15:12 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 08:15:12 INFO - Using env: {'DISPLAY': ':0', 08:15:12 INFO - 'HOME': '/home/cltbld', 08:15:12 INFO - 'LANG': 'en_US.UTF-8', 08:15:12 INFO - 'LANGUAGE': 'en_US:en', 08:15:12 INFO - 'LOGNAME': 'cltbld', 08:15:12 INFO - 'MAIL': '/var/mail/cltbld', 08:15:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:12 INFO - 'MOZ_NO_REMOTE': '1', 08:15:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:12 INFO - 'NO_EM_RESTART': '1', 08:15:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:12 INFO - 'PWD': '/builds/slave/test', 08:15:12 INFO - 'SHELL': '/bin/bash', 08:15:12 INFO - 'SHLVL': '1', 08:15:12 INFO - 'TERM': 'linux', 08:15:12 INFO - 'TMOUT': '86400', 08:15:12 INFO - 'USER': 'cltbld', 08:15:12 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:12 INFO - '_': '/tools/buildbot/bin/python'} 08:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:15: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 08:15: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 08:15: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 08:15: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 08:15:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:15:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:15:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:15:13 INFO - Installing collected packages: mozsystemmonitor 08:15:13 INFO - Running setup.py install for mozsystemmonitor 08:15:13 INFO - Successfully installed mozsystemmonitor 08:15:13 INFO - Cleaning up... 08:15:13 INFO - Return code: 0 08:15:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:15: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')]} 08:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15: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')]} 08:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:13 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 0xda01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1111b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, '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': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', '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 08:15:13 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 08:15:13 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 08:15:13 INFO - Using env: {'DISPLAY': ':0', 08:15:13 INFO - 'HOME': '/home/cltbld', 08:15:13 INFO - 'LANG': 'en_US.UTF-8', 08:15:13 INFO - 'LANGUAGE': 'en_US:en', 08:15:13 INFO - 'LOGNAME': 'cltbld', 08:15:13 INFO - 'MAIL': '/var/mail/cltbld', 08:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:13 INFO - 'MOZ_NO_REMOTE': '1', 08:15:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:13 INFO - 'NO_EM_RESTART': '1', 08:15:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:13 INFO - 'PWD': '/builds/slave/test', 08:15:13 INFO - 'SHELL': '/bin/bash', 08:15:13 INFO - 'SHLVL': '1', 08:15:13 INFO - 'TERM': 'linux', 08:15:13 INFO - 'TMOUT': '86400', 08:15:13 INFO - 'USER': 'cltbld', 08:15:13 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:13 INFO - '_': '/tools/buildbot/bin/python'} 08:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:13 INFO - Downloading/unpacking jsonschema==2.5.1 08:15: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 08:15: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 08:15: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 08:15: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 08:15:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:15:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:15:14 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 08:15:14 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 08:15:14 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 08:15:14 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 08:15:14 INFO - Downloading functools32-3.2.3-2.tar.gz 08:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:15:15 INFO - warning: no files found matching '*.txt' 08:15:15 INFO - no previously-included directories found matching 'build' 08:15:15 INFO - no previously-included directories found matching 'dist' 08:15:15 INFO - no previously-included directories found matching '.git*' 08:15:15 INFO - Installing collected packages: jsonschema, functools32 08:15:15 INFO - Running setup.py install for functools32 08:15:15 INFO - warning: no files found matching '*.txt' 08:15:15 INFO - no previously-included directories found matching 'build' 08:15:15 INFO - no previously-included directories found matching 'dist' 08:15:15 INFO - no previously-included directories found matching '.git*' 08:15:15 INFO - Successfully installed jsonschema functools32 08:15:15 INFO - Cleaning up... 08:15:15 INFO - Return code: 0 08:15:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:15:15 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')]} 08:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15:15 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')]} 08:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:15 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 0xda01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1111b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, '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': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', '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 08:15:15 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 08:15:15 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 08:15:15 INFO - Using env: {'DISPLAY': ':0', 08:15:15 INFO - 'HOME': '/home/cltbld', 08:15:15 INFO - 'LANG': 'en_US.UTF-8', 08:15:15 INFO - 'LANGUAGE': 'en_US:en', 08:15:15 INFO - 'LOGNAME': 'cltbld', 08:15:15 INFO - 'MAIL': '/var/mail/cltbld', 08:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:15 INFO - 'MOZ_NO_REMOTE': '1', 08:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:15 INFO - 'NO_EM_RESTART': '1', 08:15:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:15 INFO - 'PWD': '/builds/slave/test', 08:15:15 INFO - 'SHELL': '/bin/bash', 08:15:15 INFO - 'SHLVL': '1', 08:15:15 INFO - 'TERM': 'linux', 08:15:15 INFO - 'TMOUT': '86400', 08:15:15 INFO - 'USER': 'cltbld', 08:15:15 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:15 INFO - '_': '/tools/buildbot/bin/python'} 08:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:15:15 INFO - Cleaning up... 08:15:15 INFO - Return code: 0 08:15:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:15:15 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')]} 08:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15:15 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')]} 08:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:15 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 0xda01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1111b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, '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': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', '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 08:15:15 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 08:15:15 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 08:15:15 INFO - Using env: {'DISPLAY': ':0', 08:15:15 INFO - 'HOME': '/home/cltbld', 08:15:15 INFO - 'LANG': 'en_US.UTF-8', 08:15:15 INFO - 'LANGUAGE': 'en_US:en', 08:15:15 INFO - 'LOGNAME': 'cltbld', 08:15:15 INFO - 'MAIL': '/var/mail/cltbld', 08:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:15 INFO - 'MOZ_NO_REMOTE': '1', 08:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:15 INFO - 'NO_EM_RESTART': '1', 08:15:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:15 INFO - 'PWD': '/builds/slave/test', 08:15:15 INFO - 'SHELL': '/bin/bash', 08:15:15 INFO - 'SHLVL': '1', 08:15:15 INFO - 'TERM': 'linux', 08:15:15 INFO - 'TMOUT': '86400', 08:15:15 INFO - 'USER': 'cltbld', 08:15:15 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:15 INFO - '_': '/tools/buildbot/bin/python'} 08:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:15 INFO - Downloading/unpacking blobuploader==1.2.4 08:15:15 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 08:15:15 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 08:15:15 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 08:15:15 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 08:15:16 INFO - Downloading blobuploader-1.2.4.tar.gz 08:15:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:15:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:15:16 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 08:15:16 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 08:15:16 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 08:15:16 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 08:15:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:15:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:15:17 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 08:15:17 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 08:15:17 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 08:15:17 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 08:15:17 INFO - Downloading docopt-0.6.1.tar.gz 08:15:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:15:17 INFO - Installing collected packages: blobuploader, requests, docopt 08:15:17 INFO - Running setup.py install for blobuploader 08:15:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:15:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:15:17 INFO - Running setup.py install for requests 08:15:17 INFO - Running setup.py install for docopt 08:15:17 INFO - Successfully installed blobuploader requests docopt 08:15:17 INFO - Cleaning up... 08:15:17 INFO - Return code: 0 08:15:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:15: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')]} 08:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15: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')]} 08:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:17 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 0xda01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1111b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, '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': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', '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 08:15:17 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 08:15:17 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 08:15:17 INFO - Using env: {'DISPLAY': ':0', 08:15:17 INFO - 'HOME': '/home/cltbld', 08:15:17 INFO - 'LANG': 'en_US.UTF-8', 08:15:17 INFO - 'LANGUAGE': 'en_US:en', 08:15:17 INFO - 'LOGNAME': 'cltbld', 08:15:17 INFO - 'MAIL': '/var/mail/cltbld', 08:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:17 INFO - 'MOZ_NO_REMOTE': '1', 08:15:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:17 INFO - 'NO_EM_RESTART': '1', 08:15:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:17 INFO - 'PWD': '/builds/slave/test', 08:15:17 INFO - 'SHELL': '/bin/bash', 08:15:17 INFO - 'SHLVL': '1', 08:15:17 INFO - 'TERM': 'linux', 08:15:17 INFO - 'TMOUT': '86400', 08:15:17 INFO - 'USER': 'cltbld', 08:15:17 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:17 INFO - '_': '/tools/buildbot/bin/python'} 08:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:15:18 INFO - Running setup.py (path:/tmp/pip-ziekxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:15:18 INFO - Running setup.py (path:/tmp/pip-XjjBSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:15:18 INFO - Running setup.py (path:/tmp/pip-jLxt4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:15:18 INFO - Running setup.py (path:/tmp/pip-tGCznL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:15:18 INFO - Running setup.py (path:/tmp/pip-GUvxuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:15:18 INFO - Running setup.py (path:/tmp/pip-LqSY1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:15:18 INFO - Running setup.py (path:/tmp/pip-NihwoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:15:18 INFO - Running setup.py (path:/tmp/pip-R_Sa29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:15:18 INFO - Running setup.py (path:/tmp/pip-l401Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:15:18 INFO - Running setup.py (path:/tmp/pip-r6q3V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:15:18 INFO - Running setup.py (path:/tmp/pip-giufq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:15:18 INFO - Running setup.py (path:/tmp/pip-sRz1H3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:15:19 INFO - Running setup.py (path:/tmp/pip-8aOF2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:15:19 INFO - Running setup.py (path:/tmp/pip-g3AWoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:15:19 INFO - Running setup.py (path:/tmp/pip-eWIDGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:15:19 INFO - Running setup.py (path:/tmp/pip-KCNiuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:15:19 INFO - Running setup.py (path:/tmp/pip-i7FYPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:15:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:15:19 INFO - Running setup.py install for manifestparser 08:15:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:15:19 INFO - Running setup.py install for mozcrash 08:15:19 INFO - Running setup.py install for mozdebug 08:15:19 INFO - Running setup.py install for mozdevice 08:15:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:15:19 INFO - Running setup.py install for mozfile 08:15:19 INFO - Running setup.py install for mozhttpd 08:15:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:15:19 INFO - Running setup.py install for mozinfo 08:15:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Running setup.py install for mozInstall 08:15:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Running setup.py install for mozleak 08:15:20 INFO - Running setup.py install for mozlog 08:15:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Running setup.py install for moznetwork 08:15:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Running setup.py install for mozprocess 08:15:20 INFO - Running setup.py install for mozprofile 08:15:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Running setup.py install for mozrunner 08:15:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:15:20 INFO - Running setup.py install for mozscreenshot 08:15:20 INFO - Running setup.py install for moztest 08:15:21 INFO - Running setup.py install for mozversion 08:15:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:15:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:15:21 INFO - Cleaning up... 08:15:21 INFO - Return code: 0 08:15:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:15:21 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')]} 08:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15:21 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')]} 08:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:21 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 0xda01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1111b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, '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': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', '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 08:15:21 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 08:15:21 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 08:15:21 INFO - Using env: {'DISPLAY': ':0', 08:15:21 INFO - 'HOME': '/home/cltbld', 08:15:21 INFO - 'LANG': 'en_US.UTF-8', 08:15:21 INFO - 'LANGUAGE': 'en_US:en', 08:15:21 INFO - 'LOGNAME': 'cltbld', 08:15:21 INFO - 'MAIL': '/var/mail/cltbld', 08:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:21 INFO - 'MOZ_NO_REMOTE': '1', 08:15:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:21 INFO - 'NO_EM_RESTART': '1', 08:15:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:21 INFO - 'PWD': '/builds/slave/test', 08:15:21 INFO - 'SHELL': '/bin/bash', 08:15:21 INFO - 'SHLVL': '1', 08:15:21 INFO - 'TERM': 'linux', 08:15:21 INFO - 'TMOUT': '86400', 08:15:21 INFO - 'USER': 'cltbld', 08:15:21 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:21 INFO - '_': '/tools/buildbot/bin/python'} 08:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:15:21 INFO - Running setup.py (path:/tmp/pip-G42yfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:15:21 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)) 08:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:15:21 INFO - Running setup.py (path:/tmp/pip-tyhxlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:15:21 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)) 08:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:15:21 INFO - Running setup.py (path:/tmp/pip-mJs8PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:15:21 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)) 08:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:15:21 INFO - Running setup.py (path:/tmp/pip-DG_h82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:15:21 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)) 08:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:15:21 INFO - Running setup.py (path:/tmp/pip-Jyro8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:15:21 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)) 08:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:15:21 INFO - Running setup.py (path:/tmp/pip-cne_o2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:15:21 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)) 08:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:15:21 INFO - Running setup.py (path:/tmp/pip-bymZB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:15:21 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)) 08:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:15:21 INFO - Running setup.py (path:/tmp/pip-AZYfSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:15:21 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)) 08:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:15:21 INFO - Running setup.py (path:/tmp/pip-r6952f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:15:22 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)) 08:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:15:22 INFO - Running setup.py (path:/tmp/pip-fOwf64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:15:22 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)) 08:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:15:22 INFO - Running setup.py (path:/tmp/pip-BFgFRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:15:22 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)) 08:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:15:22 INFO - Running setup.py (path:/tmp/pip-SoU2kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:15:22 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)) 08:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:15:22 INFO - Running setup.py (path:/tmp/pip-xEHJpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:15:22 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)) 08:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:15:22 INFO - Running setup.py (path:/tmp/pip-0OGqS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:15:22 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)) 08:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:15:22 INFO - Running setup.py (path:/tmp/pip-CxKfpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:15:22 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)) 08:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:15:22 INFO - Running setup.py (path:/tmp/pip-k8D8YX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:15:22 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)) 08:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:15:22 INFO - Running setup.py (path:/tmp/pip-o8C7Nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:15:22 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)) 08:15:22 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)) 08:15:22 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)) 08:15:22 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)) 08:15:22 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)) 08:15:22 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)) 08:15:22 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)) 08:15:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:15:22 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 08:15:22 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 08:15:22 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 08:15:22 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 08:15:23 INFO - Downloading blessings-1.6.tar.gz 08:15:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:15:23 INFO - Installing collected packages: blessings 08:15:23 INFO - Running setup.py install for blessings 08:15:23 INFO - Successfully installed blessings 08:15:23 INFO - Cleaning up... 08:15:23 INFO - Return code: 0 08:15:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:15:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:15:24 INFO - Reading from file tmpfile_stdout 08:15:24 INFO - Current package versions: 08:15:24 INFO - argparse == 1.2.1 08:15:24 INFO - blessings == 1.6 08:15:24 INFO - blobuploader == 1.2.4 08:15:24 INFO - docopt == 0.6.1 08:15:24 INFO - functools32 == 3.2.3-2 08:15:24 INFO - jsonschema == 2.5.1 08:15:24 INFO - manifestparser == 1.1 08:15:24 INFO - mozInstall == 1.13 08:15:24 INFO - mozcrash == 1.0 08:15:24 INFO - mozdebug == 0.1 08:15:24 INFO - mozdevice == 0.51 08:15:24 INFO - mozfile == 1.2 08:15:24 INFO - mozhttpd == 0.7 08:15:24 INFO - mozinfo == 0.10 08:15:24 INFO - mozleak == 0.1 08:15:24 INFO - mozlog == 3.5 08:15:24 INFO - moznetwork == 0.27 08:15:24 INFO - mozprocess == 0.25 08:15:24 INFO - mozprofile == 0.28 08:15:24 INFO - mozrunner == 6.13 08:15:24 INFO - mozscreenshot == 0.1 08:15:24 INFO - mozsystemmonitor == 0.3 08:15:24 INFO - moztest == 0.8 08:15:24 INFO - mozversion == 1.4 08:15:24 INFO - psutil == 3.1.1 08:15:24 INFO - requests == 1.2.3 08:15:24 INFO - wsgiref == 0.1.2 08:15:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:24 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 0xda01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1111b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, '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': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', '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 08:15:24 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 08:15:24 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 08:15:24 INFO - Using env: {'DISPLAY': ':0', 08:15:24 INFO - 'HOME': '/home/cltbld', 08:15:24 INFO - 'LANG': 'en_US.UTF-8', 08:15:24 INFO - 'LANGUAGE': 'en_US:en', 08:15:24 INFO - 'LOGNAME': 'cltbld', 08:15:24 INFO - 'MAIL': '/var/mail/cltbld', 08:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:24 INFO - 'MOZ_NO_REMOTE': '1', 08:15:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:24 INFO - 'NO_EM_RESTART': '1', 08:15:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:24 INFO - 'PWD': '/builds/slave/test', 08:15:24 INFO - 'SHELL': '/bin/bash', 08:15:24 INFO - 'SHLVL': '1', 08:15:24 INFO - 'TERM': 'linux', 08:15:24 INFO - 'TMOUT': '86400', 08:15:24 INFO - 'USER': 'cltbld', 08:15:24 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:24 INFO - '_': '/tools/buildbot/bin/python'} 08:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:24 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)) 08:15:24 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)) 08:15:24 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)) 08:15:24 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)) 08:15:24 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)) 08:15:24 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)) 08:15:24 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)) 08:15:24 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)) 08:15:24 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)) 08:15:24 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:15:24 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 08:15:24 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 08:15:24 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 08:15:24 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 08:15:25 INFO - Downloading marionette_driver-1.4.0.tar.gz 08:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 08:15:25 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 08:15:25 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 08:15:25 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 08:15:25 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 08:15:25 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 08:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:15:25 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)) 08:15:25 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)) 08:15:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:15:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:15:25 INFO - Installing collected packages: marionette-driver, simplejson 08:15:25 INFO - Running setup.py install for marionette-driver 08:15:25 INFO - Running setup.py install for simplejson 08:15:25 INFO - building 'simplejson._speedups' extension 08:15:25 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 08:15:26 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 08:15:26 INFO - Successfully installed marionette-driver simplejson 08:15:26 INFO - Cleaning up... 08:15:26 INFO - Return code: 0 08:15:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1111b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, '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': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', '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 08:15:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:15:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:15:26 INFO - Using env: {'DISPLAY': ':0', 08:15:26 INFO - 'HOME': '/home/cltbld', 08:15:26 INFO - 'LANG': 'en_US.UTF-8', 08:15:26 INFO - 'LANGUAGE': 'en_US:en', 08:15:26 INFO - 'LOGNAME': 'cltbld', 08:15:26 INFO - 'MAIL': '/var/mail/cltbld', 08:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:26 INFO - 'MOZ_NO_REMOTE': '1', 08:15:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:26 INFO - 'NO_EM_RESTART': '1', 08:15:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:26 INFO - 'PWD': '/builds/slave/test', 08:15:26 INFO - 'SHELL': '/bin/bash', 08:15:26 INFO - 'SHLVL': '1', 08:15:26 INFO - 'TERM': 'linux', 08:15:26 INFO - 'TMOUT': '86400', 08:15:26 INFO - 'USER': 'cltbld', 08:15:26 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:26 INFO - '_': '/tools/buildbot/bin/python'} 08:15:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:15:27 INFO - Cleaning up... 08:15:27 INFO - Return code: 0 08:15:27 INFO - Running post-action listener: _resource_record_post_action 08:15:27 INFO - Running post-action listener: _start_resource_monitoring 08:15:27 INFO - Starting resource monitoring. 08:15:27 INFO - [mozharness: 2017-07-16 15:15:27.144278Z] Finished create-virtualenv step (success) 08:15:27 INFO - [mozharness: 2017-07-16 15:15:27.144740Z] Running install step. 08:15:27 INFO - Running pre-action listener: _resource_record_pre_action 08:15:27 INFO - Running main action method: install 08:15:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:15:27 INFO - Reading from file tmpfile_stdout 08:15:27 INFO - Detecting whether we're running mozinstall >=1.0... 08:15:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:15:27 INFO - Reading from file tmpfile_stdout 08:15:27 INFO - Output received: 08:15:27 INFO - Usage: mozinstall [options] installer 08:15:27 INFO - Options: 08:15:27 INFO - -h, --help show this help message and exit 08:15:27 INFO - -d DEST, --destination=DEST 08:15:27 INFO - Directory to install application into. [default: 08:15:27 INFO - "/builds/slave/test"] 08:15:27 INFO - --app=APP Application being installed. [default: firefox] 08:15:27 INFO - mkdir: /builds/slave/test/build/application 08:15:27 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'] 08:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:15:45 INFO - Reading from file tmpfile_stdout 08:15:45 INFO - Output received: 08:15:45 INFO - /builds/slave/test/build/application/firefox/firefox 08:15:45 INFO - Running post-action listener: _resource_record_post_action 08:15:45 INFO - [mozharness: 2017-07-16 15:15:45.379424Z] Finished install step (success) 08:15:45 INFO - [mozharness: 2017-07-16 15:15:45.379680Z] Running setup-mitmproxy step. 08:15:45 INFO - Running pre-action listener: _resource_record_pre_action 08:15:45 INFO - Running main action method: setup_mitmproxy 08:15:45 INFO - Skipping: mitmproxy is not required 08:15:45 INFO - Running post-action listener: _resource_record_post_action 08:15:45 INFO - [mozharness: 2017-07-16 15:15:45.380831Z] Finished setup-mitmproxy step (success) 08:15:45 INFO - [mozharness: 2017-07-16 15:15:45.381054Z] Running run-tests step. 08:15:45 INFO - Running pre-action listener: _resource_record_pre_action 08:15:45 INFO - Running pre-action listener: _set_gcov_prefix 08:15:45 INFO - Running main action method: run_tests 08:15:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:15:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:15:45 INFO - Python 2.7.3 08:15:45 INFO - Return code: 0 08:15:45 INFO - grabbing minidump binary from tooltool 08:15:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:15:45 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 0x110ed00>, '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 0x110fc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1110090>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:15:45 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 08:15:45 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 08:15:45 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 08:15:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:15:45 INFO - Return code: 0 08:15:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:15:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:15:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:15:45 INFO - ENV: RUST_BACKTRACE is now 1 08:15:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:15:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:15:45 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/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 08:15:45 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/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 08:15:45 INFO - Using env: {'DISPLAY': ':0', 08:15:45 INFO - 'HOME': '/home/cltbld', 08:15:45 INFO - 'LANG': 'en_US.UTF-8', 08:15:45 INFO - 'LANGUAGE': 'en_US:en', 08:15:45 INFO - 'LOGNAME': 'cltbld', 08:15:45 INFO - 'MAIL': '/var/mail/cltbld', 08:15:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:15:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:45 INFO - 'MOZ_NO_REMOTE': '1', 08:15:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:15:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:15:45 INFO - 'NO_EM_RESTART': '1', 08:15:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:45 INFO - 'PWD': '/builds/slave/test', 08:15:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:15:45 INFO - 'RUST_BACKTRACE': '1', 08:15:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:15:45 INFO - 'SHELL': '/bin/bash', 08:15:45 INFO - 'SHLVL': '1', 08:15:45 INFO - 'TERM': 'linux', 08:15:45 INFO - 'TMOUT': '86400', 08:15:45 INFO - 'USER': 'cltbld', 08:15:45 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566', 08:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:45 INFO - '_': '/tools/buildbot/bin/python'} 08:15:45 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/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 08:15:45 INFO - mozversion application_buildid: 20170716143035 08:15:45 INFO - mozversion application_changeset: bc3f73d54feeaf0e97b30496574357f05b2d5307 08:15:45 INFO - mozversion application_display_name: Nightly 08:15:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:15:45 INFO - mozversion application_name: Firefox 08:15:45 INFO - mozversion application_remotingname: firefox 08:15:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:15:45 INFO - mozversion application_vendor: Mozilla 08:15:45 INFO - mozversion application_version: 56.0a1 08:15:45 INFO - mozversion platform_buildid: 20170716143035 08:15:45 INFO - mozversion platform_changeset: bc3f73d54feeaf0e97b30496574357f05b2d5307 08:15:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:15:45 INFO - mozversion platform_version: 56.0a1 08:15:45 INFO - using testdate: 1500218145 08:15:45 INFO - actual date: 1500218145 08:15:45 INFO - starting webserver on 'localhost:58334' 08:15:45 INFO - SUITE-START | Running 2 tests 08:15:45 INFO - TEST-START | dromaeo_css 08:15:45 INFO - Initialising browser for dromaeo_css test... 08:15:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58334/getInfo.html -profile /tmp/tmpmTHC3t/profile 08:15:45 INFO - TEST-INFO | started process 22287 (/builds/slave/test/build/application/firefox/firefox http://localhost:58334/getInfo.html) 08:15:52 INFO - TEST-INFO | 22287: exit 0 08:15:52 INFO - Browser initialized. 08:15:52 INFO - Running cycle 1/1 for dromaeo_css test... 08:15:52 INFO - TEST-INFO | started process 22510 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmTHC3t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:16:04 INFO - PID 22510 | RSS: Main: 169832448 08:16:04 INFO - PID 22510 | 08:19:01 INFO - PID 22510 | Cycle 1(1): loaded http://localhost:58334/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58334/tests/dromaeo/cssquery-ext.html) 08:19:03 INFO - PID 22510 | RSS: Main: 177156096 08:19:03 INFO - PID 22510 | 08:22:05 INFO - PID 22510 | Cycle 1(1): loaded http://localhost:58334/tests/dromaeo/cssquery-ext.html (next: http://localhost:58334/tests/dromaeo/cssquery-jquery.html) 08:22:05 INFO - PID 22510 | RSS: Main: 176480256 08:22:05 INFO - PID 22510 | 08:25:07 INFO - PID 22510 | Cycle 1(1): loaded http://localhost:58334/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58334/tests/dromaeo/cssquery-mootools.html) 08:25:08 INFO - PID 22510 | RSS: Main: 178749440 08:25:08 INFO - PID 22510 | 08:28:09 INFO - PID 22510 | Cycle 1(1): loaded http://localhost:58334/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58334/tests/dromaeo/cssquery-prototype.html) 08:28:10 INFO - PID 22510 | RSS: Main: 177168384 08:28:10 INFO - PID 22510 | 08:31:13 INFO - PID 22510 | Cycle 1(1): loaded http://localhost:58334/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58334/tests/dromaeo/cssquery-yui.html) 08:31:14 INFO - PID 22510 | RSS: Main: 175951872 08:31:14 INFO - PID 22510 | 08:34:15 INFO - PID 22510 | Cycle 1(1): loaded http://localhost:58334/tests/dromaeo/cssquery-yui.html (next: http://localhost:58334/tests/dromaeo/cssquery-dojo.html) 08:34:16 INFO - PID 22510 | RSS: Main: 179699712 08:34:16 INFO - PID 22510 | 08:34:16 INFO - PID 22510 | __start_tp_report 08:34:16 INFO - PID 22510 | _x_x_mozilla_page_load 08:34:16 INFO - PID 22510 | _x_x_mozilla_page_load_details 08:34:16 INFO - PID 22510 | |i|pagename|runs| 08:34:16 INFO - PID 22510 | |0;dojo.html;3062.800917940334;3065.6474505431884;3086.074177746677;3095.14883407063;3124.3751249750053;5845.853853653655;5890.793822216223;5911.763529458827;5938.940610593892;5993.400659934005;5969.044952807551;5988.940110598893;5993.370696076914;5993.850153746159;6012.5791194616395;5478.233047373371;5490.066688662937;5496.7526461309135;5500.092484740013;5508.834734957958;5493.395726470085;5500.3949565547755;5502.669839809604;5503.972480137593;5506.724663766816;5501.724913754317;5506.284183056197;5507.201455788928;5508.338999320087;5513.228148059275;5506.256655351556;5513.58648101394;5522.585806064561;5527.972360138214;5534.363548191949;5490.835274941758;5515.283013208297;5519.69641669708;5521.199426083235;5544.306961629796;5966.552508561832;5990.2212712347555;6042.909356359658;6049.758009679652;6075.453209211192;6334.113224148623;6374.394432528902;6379.362063793584;6460.838479038061;6465.191851018622;6464.870702585922;6490.4807615390655;6508;6522.249941256769;6533.477321814244;6005.429484198994;6047.304559975615;6055.636661800306;6064.332923378425;6068.908966365508;5766.682832444172;6570.704318305736;6660.267370589231;6679.499037572202;6730.798076057726;6312.652804095727;6326.240851097806;6340.8414789629705;6413.935860641427;6417.711202995783;6331.746730130743;6436.16329877113;6443.935560644427;6460.224773027267;6539.280679125292;6241.937580624226;6312.495000399957;6357.523185761086;6410.391012853865;6451.096846441502;5522;6340.461060809791;6368.42684158428;6374.585651932703;6587;5091.592672586185;5206.76569554371;5234.528892399702;5288.286081379041;5312.814051508256;4490.124425737015;4491.663125265618;4495.9325610115875;4506.2564676828615;4509.932351014738;5533.916991245134;5548.69482178476;5549.805756798573;5557.916631250535;5567.498925096761;6433.774817881352;6487.967560162169;6533.444657204191;6533.67331633426;6543.574667646683;5460.235567020621;5482.095454250004;5502.9174562381595;5554.1946417770005;5577.665340079527;5648.265725455664;5709.942900570941;5776.249087618667;5802.88394232113;5811.244538210006;6024.57827952048;6135.263768347827;6163.876722465526;6164.352742961924;6191.349908259658;6378.553501254961;6425.935740642721;6492.091107244895;6504.349565043647;6508.186476690414;6088.360722124113;6157.630542167484;6161.784337548164;6193;6265.749370025148;6364.777232797015;6378.712957916812;6423.614583125028;6457.741690332522;6482.060101285381;6477.1579694639395;6504.804855854332;6577.769778057745;6588.934110658832;6615.17310336208;6165;6264.185655864891;6267.279262884645;6298.874022519615;6382.6170429774365;6252.874482593177;6271.62370257786;6277.246730392382;6281.560290779602;6291.685415729286;6114.449699527064;6136.140940268277;6147.139400483847;6167.074938759042;6194.194754681863;5562.922626940867;6307.432331090224;6359.618422894641;6392.328805475544;6456.128422663069;6140.631562106288;6159.62441314554;6243.157173781481;6246.219222597176;6264.21697287839 08:34:16 INFO - PID 22510 | |1;ext.html;747.6149782861827;791.1100012485954;821.3757544266358;841.0706169682501;848.872669099635;39946.600533994686;41108.58891411085;41255.793721031354;41272.17455650885;41316.38025429621;37335.253294934155;37492.81253593735;37739.433908491395;37785.24429511415;37847;6659.300773418782;6718.865622687544;6804.557703749265;6809.250982391958;6821.147356580427;6613.570117942319;6768.966155169217;6790.9320906791045;6791.592504449724;6808.319168083177;6721.462283017371;6755.560888542253;6781.355771201728;6794.218664853531;6800.251972283069;6634.038064480647;6709.731610735565;6732.764353247613;6741.494387920926;6747.325267473262;6132.172156758825;6137.048757442603;6179.938200618026;6186.19579454668;6204.565680402374;32749.345013099606;33095;33133.502997455056;33179;33191.17022074467;3360.462326027824;3375.8143302118374;3399.762016658835;3400.778949368283;3403.0471467989005;26478;30502.54246186309;31310;31383.84308078445;31465.21336966571;4936.456989731127;4948.282499037619;4960.032793605248;4969.27945447908;5010.148274793294;186777;188880;189171.05414472977;191667;193459;46681.29978050332;47543.76228118906;47745;47856.76071619689;48186;50622;50678;50684.74657626688;50764.23853642198;50784.49215507872;15861.444849430445;16069.839301606835;16123.355065797472;16128.193590320438;16238;13352.933235333956;13365.866341336656;13387;13404.597862064154;13404.932975335061;14469.27653617336;14499.420023199165;14579.635509112357;14583.126796867296;14649.414023438943;5640.5769567281795;5751.376632721359;5755.568332375088;5804.448577385226;5906.645601263938;981.6613268422383;994.4132961552574;1016.9389836609827;1037.6938803053117;1042.103790739963;22139.889300553394;22605.849907740307;22813.885930570243;23349.416264593183;23578.29265122049;1090.7218659241842;1103.6137351926761;1127.0814286356592;1131.5473810475876;1133.0822034152363;11903.226290291102;12021.879781202077;12164.965977891978;12375.443105060294;12440.253584784941;215203;224144;224203;224267;225332;51263;51544;51666.741666291426;51843.48156518462;51866.22200666993;1449.6013596260902;1455.4396557325294;1485.5320574019365;1488.5608745420127;1489.4861272860846;22020.56866303685;22058.33824985253;22591.20930767415;22623.98192081393;23113.537729244985;35587;35641.46537801987;35866.82066589755;35868.282634346644;36014.27971440504;193259;193968.0301598483;194370;194427;194780;33797.155071123416;35433.64566354406;35975;37747.43378849374;37785.433218502294;46020.07959840851;46668.299975501075;46956.295655564514;47313.763431182626;47582;31438;32765.017049488553;33515.4972675415;33917.321653566294;34050.659493404746;32499;33097.83451082825;33261.667383325854;33352;33742.32515349728;36725.63274367222;36942;37802.43296350612;38090.238195236496;38146;31154;31579;32522.186945326557;32599.348013039133;32883.83558082194;23531.41171470727;23589.174378896656;23596.31909804967;23824.88087559551;24418.389540260923 08:34:16 INFO - PID 22510 | |2;jquery.html;573.5870173475099;579.6840721806612;583.8657108864958;598.267122774601;609.3662590905459;21950.670739938894;22783.202587909385;22951.08195672175;23101;23125.537489250204;27078.59382109265;27478.725212747868;27636.309092272702;27641.447171056567;27653;6544.967275163617;6756.290589488107;6763.357481039294;6782.050512928194;6809.829754256134;6193.628382297076;6332.366763323676;6490;6605.0422688710105;6631;6443.130177426057;6525.902111468332;6547.181602299713;6549.214094308666;6558.8688226235445;6522.836929076763;6527;6528.869422611545;6532.314107018766;6536.379043990813;6100.206973093514;6109.144719739195;6109.5723299369065;6110.236220472441;6117.5717699761035;28160.718392816016;29128;29685.70314296851;29755.10734677963;31091.689083109333;1898.5443493561584;1936.6320399124122;1941.3981665683684;1951.1219951021956;1959;19072;19644.607107857904;19646.312379066807;19650.70523942142;19671.114799834042;2335.859848409101;2352;2363.420961864337;2401.591729406005;2430.2466235466886;133853.3307333467;138391;138421.99999999898;138596;138626.30686846602;14584.854151458561;15309.311081001382;15342.769858452131;15463.149526775911;15695.764563531557;24038.759612404003;24067.759322406553;24092.39769005789;24193.63709544358;24216.636750448757;10172.389656620626;10261.640842570474;10364.274500785023;10400.427976461368;10684.412357320422;9624.51877406127;9649.469279189712;9658.130768230892;9670.323077384515;9867.11195992364;8624;9317.953410232905;9348.439093654402;9423.623055077856;9449.480278584606;2684.597310403455;2739.643846300008;2803.9859800701274;2889.4510043091746;2900.390917907221;955.5031383680447;958.6021800952666;974.3861367338532;985.9211263098935;993.1210878372548;19820.20719171217;19842.206311747657;20155.798442015686;20515.794842051688;20638.38084857457;1009.3388830316155;1016.4307987527009;1046.795874804406;1047.2878442658912;1054.583439541377;10991.907583707412;11184.720381990515;11205.495752691148;11396.259243149168;11397.487113079931;411583;427958;437851;446864;448072;109093;111309;115571;116154.419227905;116293;1161.7153797319543;1192.099964526794;1210.818377243403;1219.3354621731178;1221.82283568882;16665.083420411993;17754.467365978526;17984.460466186036;18384.816151838844;18718.812811871707;51842;56610.15084773649;57028;58915;58968;68947.3105268961;69273.3072669287;69440.65279673772;69498.30501694918;70263;11919;14670;15086.698266034837;15099.169545675093;15107.39570417171;17857.28570857203;21259;23668.408289792893;24019.879900600386;24127.51744965126;18382.540436489195;18476.907615462373;18611.441656750318;18876.905615471835;18934.715979260596;15996.840031599535;16348.918255408647;16511.33954641801;16986.320547177973;17127.743083854002;18805;18919.810801891806;18934.715979260047;18986.905065474584;19179.328723495168;14740.631484212981;14960.40158393652;15329.77005344943;15341.769873451683;15358.308876100378;19140.330088447394;19232.23071077141;19315.130819112892;19324.8067519323;19559 08:34:16 INFO - PID 22510 | |3;mootools.html;1363.359221166052;1422.374155371637;1434.6126545832633;1435.1532595768495;1447.8117844680182;13288.601341959744;13824.93087534561;13830.585082447518;13852;13892.30538473076;12937.353132343393;13135.6716082098;13207.273599952;13265.40305686244;13293.468261269538;2696.0968075694664;2773.4730401223806;2773.681026681927;2774.8057635965492;2775.9028434004813;2654.2568080937367;2727.1136880513814;2727.359070618403;2730.5358089124898;2734.4667789781;2236.586231547169;2676.050002249193;2717.1440996086267;2736.343277613378;2742.2184677366954;2278.646809744484;2281.3726225288015;2298.0118549023996;2599.896004159831;2702.3244188952763;2632.06561670608;2660.1221596872983;2663.893444262227;2666.1068542038443;2668.146193218171;13115;13295.933520332434;13345.73308533824;13354.26551539665;13355.131916425402;7353.7058517659225;7617;7697.268759467842;7710.421718371144;7717.807054823618;4912.14037543432;5093.159628661267;5249.02892964799;5280.419153893069;5307.203919412081;1836.1370156026646;1851.787044489874;1879.783824860145;1924.7594050743658;1928.2865339824214;22314.55370892573;22430.214942477098;22569.54860902789;22584.774152258433;22620.88689556558;6326.68366581669;6465.224173099258;6466.256380516301;6537.901931471032;6629.751421803282;15507.147106909002;15624.76562851558;15672.05967641945;15682.137482438347;15691.450799221973;7395.371393431618;7418.777436676908;7509.812254693567;7543.622818859035;7556.622168891534;6900.137482814648;6940.167179938439;6989.126359205099;7011.26381729911;7088.610126442992;5044.9495505045215;5151.948480515147;5163.432022477525;5230;5316.7075810830775;1484.9925750371178;1489.791429199913;1492.2911616981846;1517.9089254644537;1539.507357645565;891.5809569502453;899.2176806178667;908.3323757038571;922.9169374756304;926.0693003531408;7062.858742825218;7092.4326053915565;7192.892106618405;7198;7212.3148300911325;708.3235510087853;739.2755100002028;991.9355241909398;1025.29254814178;1032.6179313653995;4822.95177048225;5052.722100284446;5148.794048238104;5186.4035635902355;5189.792408303701;18088.728669069973;18956.905215473835;19043.238270469024;19049.047547622842;19069.237230510626;18212.18045187943;18501.53746156357;18548;18834;18989.715154272697;10634.946825265824;10798.946005269923;10803.567857285778;10870.61952831647;10917.836232456519;7576.848463030598;7943.364530837634;7986.84026319482;8103.594820258845;8494.32045436376;15726.606834829221;15862.127582983047;16421.17894105266;16425.507234782977;16588.66822663516;15967.041977481389;16506.58733531671;16800;16801;17004.489865304062;11206;11540.480678369326;11551.249168804;11557.942210288895;11647.242929209575;12809.103362764517;12933.353332333158;12953.158044727063;12977.221366718028;13000.024998124989;9910.058544438267;10076.445795481319;10307.536160872929;10326.432046237529;10454.32046916948;9713.611455541983;9861.211103111877;9930.25523085757;9980.900190998287;10025.799484010426;9263.490508022123;9610.567524461276;9621.326507144433;9629.566669499749;9641;6880.518363714492;7272.745453909088;7273.454490913097;7299.817504562216;7314.08573928259;7122;7360.889586656097;7391.519551229153;7470;7563.7352692655495 08:34:16 INFO - PID 22510 | |4;prototype.html;486.5232072568882;501.2481278082877;520.0976306108901;525.90270799902;526.6024151765416;6032.547558933081;6112.829945369396;6113.065728450347;6129.38706129388;6136.018237082068;5747.281589801275;5980.970095149518;6002.789902353418;6011.188489553909;6034.698265086739;4819.2771084337455;4874.122657921564;4897.094037603055;4917.3361596184595;4958.380202474691;4818.542238487338;4839.903201935959;4852.272159176134;4886.242632391985;4912.606991440676;4844.07962487128;4890.315355850184;4903.754812259391;4917.680350777206;4922.237053256733;4701.388819453467;4819.927701084486;4834.806607735686;4896.4124305083615;4898.191798353268;4357.934630980538;4358.389825424443;4388.166248412791;4429.756363400012;4445.110977804452;5844.620099693507;5856.853578660525;5863.677497737623;5891.849474941659;5912.142739302777;5532.419095994943;5617.747201375907;5638.731285460739;5655.660660360391;5658.462446067617;6219.440250377488;6319.494440444753;6319.526035547352;6332.68336583169;6419.87160256797;4792.856214313541;4818.7831547580445;4820.373351464323;4842.588379987706;4849.321095046696;6711.56374835639;6789.355011273921;6790.185177778649;6804.659767011679;6810.829729256809;4964.131277026506;4972;4995.625328100407;5006.799728010838;5011.824586139468;4932.112219800399;5023.070731914534;5047.369078865142;5050.37380562113;5056.772445239973;5472.91790623141;5527.889442211133;5558.77764889408;5561.416051314553;5589.944100559023;6200;6254;6350.364963503613;6409.692510696145;6427.100205971168;1698.7791587093598;1733.8643188711414;1736.461696873973;1745.8690598205185;1757.0847775924303;1097.8133717268083;1203.5366383942246;1204.3376143121247;1210.4734440518403;1228.42264135856;5350.866228344323;5435.646682965595;5493.670379777226;5511.173324001352;5653.265075540154;6461.8061458156335;6462.838429039311;6521.250056243593;6551.377619126175;6572.408483236436;5429.782808687608;5459.836204913859;5507.146392309199;5516.475934786189;5541.362743284493;3406.2846802171325;3440.9655903440644;3442.4492081266876;3468.670476304747;3502.614712381636;6246.6876656167005;6327.746890124344;6381.8085457436355;6409.743610255631;6413.711382987777;6656.73373065072;6751.594904305757;6758.715843989627;6769.295339738933;6797.456203503708;6385.361463853577;6405.647689377036;6407.103005579223;6423.967880160662;6462.029081130207;6457.870842583028;6459.709313080829;6524.347565243438;6548.1487406638735;6554;6466.870662586816;6532.706028228646;6541;6562.868742625025;6571.671416429255;6269.62382257066;6323.336049714898;6338.334474880256;6390;6413.8396540085005;4881.877953051202;4900.30939325932;4918.409790825124;4935.827246046371;4939.357883475125;6522.21733391996;6547.901781473377;6579.703913323816;6590.90113648286;6644.534882558174;6287.321916678794;6314;6366.2360516736435;6415.230172379158;6426.550141490051;6302.361386039571;6308.684565771601;6351.301356850835;6365.236171659245;6369.9363006371195;6242.313345531897;6243.344448832807;6246.156768836149;6252.5935814171935;6329.208848893888;6241.500679945684;6309.274433440213;6392.616443013434;6401.743930242925;6405.19935008124;5722.742476588647;6101.664408457577;6132.9080063799975;6154;6186.257649082139 08:34:16 INFO - PID 22510 | |5;yui.html;886.7916039730662;900.8648702694597;911.7766147293914;921.6451666108546;929.7768535551468;18163.99999999998;18249.36127235547;18425.723614145765;18464.630707385844;18505.2597896084;17768.200430980603;18504.352347667835;19566.31517896874;19652.803471965275;19799;1022.1771473963456;1047.3611097230682;1051.8790339111024;1058.4443167337133;1063.8457423673563;1060.6923992042298;1062.8140075486797;1062.8671416072953;1065.9307145035548;1070.4273213830602;1129.1531351486385;1132.1735133352659;1134.9489272982694;1134.9489272982735;1137.385811661706;1009.5053423822311;1069.4652673663168;1097.0839349143475;1102.4377567440663;1103.382106020627;796.2992566541407;797.9002624671916;798.2695833312512;799.0371602219312;799.3245707377257;18311.08444577779;18577.651666533366;18668.81331186698;18870.528236794053;18949;20116.29592964239;20145.8992705037;20405.183792648277;20425.978701064887;20702.068406921724;15115.093094414371;15182.316795744218;15204.087754734752;15242.009269397453;15333.539993800205;3364.192593777476;3481.8259087045544;3511.894643160709;3524.559430071241;3538.716902647782;101915;104254.47872760663;110901;113032.43483782529;116132.41933790362;16542.172891355385;16784.41254556085;16841.578960526083;16890.155492225585;16940.661186776197;27789.722102778713;28819.135425937257;29422.558661620093;29610.555841662394;29778;8617.612207450555;8682.175193356621;8713.869291960626;8775.254103401156;8830.116988301119;4120.114175452232;4196.559361267084;4226.873193804191;4245.1934132514725;4260.147970405931;4933.407991041026;5096.362954630671;5124.436312005676;5129.410117836422;5135.512126347991;1053.0680347892016;1084.9186311026547;1086.0931122512632;1105.834124881258;1108.8004159251411;561.3488353509908;637.4072112935015;662.3012721578781;712.4264966701751;730.8720973829559;10785.298955567863;11260.43697815106;11476.081913446904;11557.479913403917;12477.5008999639;616.921561408577;639.7409049334934;650.5088658063132;669.1468377818223;711.1714852197235;5195.986782577361;5276.604254680914;5296.09966305729;5397.946020539823;5535.6955367455175;95019.52490237505;95542.52228738951;96279.87213458725;96905;98456.50771746096;78058.60970695224;81424.18575814285;83153;89482.55258723664;89993.55003224942;74958.62520687471;77208.22791772123;77578.61210693911;77920;79394.20605793867;11194.776104477702;11376.260543064674;11606.187566870238;11611.245269057823;11762.529498819951;33292.50061249132;35723.64276357307;35827;36953.630463695015;37177.6282237185;42605.57394426016;43829.34255986226;44027.559724402345;44293.33559996667;45180.322295166254;17204.91397543004;17774.733378999066;17967.73048404301;18099.728504072187;18371.35700250538;19015.52461188481;19240.903795481492;19261.22955081837;19511.512212194808;19645.017749112772;15500.84499154994;16653.91673041627;16743.581410464347;16774.49676509707;17001.744973825156;16749.581260468585;16829.242684079;18598.907005464887;19419.902900485406;19516.21935122579;17033.233504492575;17279.136043198043;17407.91296043512;17454.91272543629;17848.643027139125;12306.81539776922;12386.106328714362;12547.184433011826;12598.244105353708;13325.600231993056;13249.271290079138;13386.866131338562;13710.65723356924;13822.65443363924;13953.232572208626 08:34:16 INFO - PID 22510 | __end_tp_report 08:34:16 INFO - PID 22510 | __start_cc_report 08:34:16 INFO - PID 22510 | _x_x_mozilla_cycle_collect,338 08:34:16 INFO - PID 22510 | __end_cc_report 08:34:16 INFO - PID 22510 | __startTimestamp1500219256151__endTimestamp 08:34:16 INFO - PID 22510 | 08:34:16 INFO - PID 22510 | ------- Summary: start ------- 08:34:16 INFO - PID 22510 | Number of tests: 6 08:34:16 INFO - PID 22510 | 08:34:16 INFO - PID 22510 | [#0] dojo.html Cycles:165 Average:5918.77 Median:6136.14 stddev:691.72 (11.3%) stddev-sans-first:656.55 08:34:16 INFO - PID 22510 | Values: 3062.8 3065.6 3086.1 3095.1 3124.4 5845.9 5890.8 5911.8 5938.9 5993.4 5969.0 5988.9 5993.4 5993.9 6012.6 5478.2 5490.1 5496.8 5500.1 5508.8 5493.4 5500.4 5502.7 5504.0 5506.7 5501.7 5506.3 5507.2 5508.3 5513.2 5506.3 5513.6 5522.6 5528.0 5534.4 5490.8 5515.3 5519.7 5521.2 5544.3 5966.6 5990.2 6042.9 6049.8 6075.5 6334.1 6374.4 6379.4 6460.8 6465.2 6464.9 6490.5 6508.0 6522.2 6533.5 6005.4 6047.3 6055.6 6064.3 6068.9 5766.7 6570.7 6660.3 6679.5 6730.8 6312.7 6326.2 6340.8 6413.9 6417.7 6331.7 6436.2 6443.9 6460.2 6539.3 6241.9 6312.5 6357.5 6410.4 6451.1 5522.0 6340.5 6368.4 6374.6 6587.0 5091.6 5206.8 5234.5 5288.3 5312.8 4490.1 4491.7 4495.9 4506.3 4509.9 5533.9 5548.7 5549.8 5557.9 5567.5 6433.8 6488.0 6533.4 6533.7 6543.6 5460.2 5482.1 5502.9 5554.2 5577.7 5648.3 5709.9 5776.2 5802.9 5811.2 6024.6 6135.3 6163.9 6164.4 6191.3 6378.6 6425.9 6492.1 6504.3 6508.2 6088.4 6157.6 6161.8 6193.0 6265.7 6364.8 6378.7 6423.6 6457.7 6482.1 6477.2 6504.8 6577.8 6588.9 6615.2 6165.0 6264.2 6267.3 6298.9 6382.6 6252.9 6271.6 6277.2 6281.6 6291.7 6114.4 6136.1 6147.1 6167.1 6194.2 5562.9 6307.4 6359.6 6392.3 6456.1 6140.6 6159.6 6243.2 6246.2 6264.2 08:34:16 INFO - PID 22510 | 08:34:16 INFO - PID 22510 | [#1] ext.html Cycles:180 Average:36961.04 Median:3400.27 stddev:52569.25 (1546.0%) stddev-sans-first:52646.40 08:34:16 INFO - PID 22510 | Values: 747.6 791.1 821.4 841.1 848.9 39946.6 41108.6 41255.8 41272.2 41316.4 37335.3 37492.8 37739.4 37785.2 37847.0 6659.3 6718.9 6804.6 6809.3 6821.1 6613.6 6769.0 6790.9 6791.6 6808.3 6721.5 6755.6 6781.4 6794.2 6800.3 6634.0 6709.7 6732.8 6741.5 6747.3 6132.2 6137.0 6179.9 6186.2 6204.6 32749.3 33095.0 33133.5 33179.0 33191.2 3360.5 3375.8 3399.8 3400.8 3403.0 26478.0 30502.5 31310.0 31383.8 31465.2 4936.5 4948.3 4960.0 4969.3 5010.1 186777.0 188880.0 189171.1 191667.0 193459.0 46681.3 47543.8 47745.0 47856.8 48186.0 50622.0 50678.0 50684.7 50764.2 50784.5 15861.4 16069.8 16123.4 16128.2 16238.0 13352.9 13365.9 13387.0 13404.6 13404.9 14469.3 14499.4 14579.6 14583.1 14649.4 5640.6 5751.4 5755.6 5804.4 5906.6 981.7 994.4 1016.9 1037.7 1042.1 22139.9 22605.8 22813.9 23349.4 23578.3 1090.7 1103.6 1127.1 1131.5 1133.1 11903.2 12021.9 12165.0 12375.4 12440.3 215203.0 224144.0 224203.0 224267.0 225332.0 51263.0 51544.0 51666.7 51843.5 51866.2 1449.6 1455.4 1485.5 1488.6 1489.5 22020.6 22058.3 22591.2 22624.0 23113.5 35587.0 35641.5 35866.8 35868.3 36014.3 193259.0 193968.0 194370.0 194427.0 194780.0 33797.2 35433.6 35975.0 37747.4 37785.4 46020.1 46668.3 46956.3 47313.8 47582.0 31438.0 32765.0 33515.5 33917.3 34050.7 32499.0 33097.8 33261.7 33352.0 33742.3 36725.6 36942.0 37802.4 38090.2 38146.0 31154.0 31579.0 32522.2 32599.3 32883.8 23531.4 23589.2 23596.3 23824.9 24418.4 08:34:16 INFO - PID 22510 | 08:34:16 INFO - PID 22510 | [#2] jquery.html Cycles:180 Average:33272.27 Median:23113.27 stddev:73994.76 (320.1%) stddev-sans-first:74161.61 08:34:16 INFO - PID 22510 | Values: 573.6 579.7 583.9 598.3 609.4 21950.7 22783.2 22951.1 23101.0 23125.5 27078.6 27478.7 27636.3 27641.4 27653.0 6545.0 6756.3 6763.4 6782.1 6809.8 6193.6 6332.4 6490.0 6605.0 6631.0 6443.1 6525.9 6547.2 6549.2 6558.9 6522.8 6527.0 6528.9 6532.3 6536.4 6100.2 6109.1 6109.6 6110.2 6117.6 28160.7 29128.0 29685.7 29755.1 31091.7 1898.5 1936.6 1941.4 1951.1 1959.0 19072.0 19644.6 19646.3 19650.7 19671.1 2335.9 2352.0 2363.4 2401.6 2430.2 133853.3 138391.0 138422.0 138596.0 138626.3 14584.9 15309.3 15342.8 15463.1 15695.8 24038.8 24067.8 24092.4 24193.6 24216.6 10172.4 10261.6 10364.3 10400.4 10684.4 9624.5 9649.5 9658.1 9670.3 9867.1 8624.0 9318.0 9348.4 9423.6 9449.5 2684.6 2739.6 2804.0 2889.5 2900.4 955.5 958.6 974.4 985.9 993.1 19820.2 19842.2 20155.8 20515.8 20638.4 1009.3 1016.4 1046.8 1047.3 1054.6 10991.9 11184.7 11205.5 11396.3 11397.5 411583.0 427958.0 437851.0 446864.0 448072.0 109093.0 111309.0 115571.0 116154.4 116293.0 1161.7 1192.1 1210.8 1219.3 1221.8 16665.1 17754.5 17984.5 18384.8 18718.8 51842.0 56610.2 57028.0 58915.0 58968.0 68947.3 69273.3 69440.7 69498.3 70263.0 11919.0 14670.0 15086.7 15099.2 15107.4 17857.3 21259.0 23668.4 24019.9 24127.5 18382.5 18476.9 18611.4 18876.9 18934.7 15996.8 16348.9 16511.3 16986.3 17127.7 18805.0 18919.8 18934.7 18986.9 19179.3 14740.6 14960.4 15329.8 15341.8 15358.3 19140.3 19232.2 19315.1 19324.8 19559.0 08:34:16 INFO - PID 22510 | 08:34:16 INFO - PID 22510 | [#3] mootools.html Cycles:180 Average:8530.55 Median:2665.00 stddev:5721.12 (214.7%) stddev-sans-first:5711.82 08:34:16 INFO - PID 22510 | Values: 1363.4 1422.4 1434.6 1435.2 1447.8 13288.6 13824.9 13830.6 13852.0 13892.3 12937.4 13135.7 13207.3 13265.4 13293.5 2696.1 2773.5 2773.7 2774.8 2775.9 2654.3 2727.1 2727.4 2730.5 2734.5 2236.6 2676.1 2717.1 2736.3 2742.2 2278.6 2281.4 2298.0 2599.9 2702.3 2632.1 2660.1 2663.9 2666.1 2668.1 13115.0 13295.9 13345.7 13354.3 13355.1 7353.7 7617.0 7697.3 7710.4 7717.8 4912.1 5093.2 5249.0 5280.4 5307.2 1836.1 1851.8 1879.8 1924.8 1928.3 22314.6 22430.2 22569.5 22584.8 22620.9 6326.7 6465.2 6466.3 6537.9 6629.8 15507.1 15624.8 15672.1 15682.1 15691.5 7395.4 7418.8 7509.8 7543.6 7556.6 6900.1 6940.2 6989.1 7011.3 7088.6 5044.9 5151.9 5163.4 5230.0 5316.7 1485.0 1489.8 1492.3 1517.9 1539.5 891.6 899.2 908.3 922.9 926.1 7062.9 7092.4 7192.9 7198.0 7212.3 708.3 739.3 991.9 1025.3 1032.6 4823.0 5052.7 5148.8 5186.4 5189.8 18088.7 18956.9 19043.2 19049.0 19069.2 18212.2 18501.5 18548.0 18834.0 18989.7 10634.9 10798.9 10803.6 10870.6 10917.8 7576.8 7943.4 7986.8 8103.6 8494.3 15726.6 15862.1 16421.2 16425.5 16588.7 15967.0 16506.6 16800.0 16801.0 17004.5 11206.0 11540.5 11551.2 11557.9 11647.2 12809.1 12933.4 12953.2 12977.2 13000.0 9910.1 10076.4 10307.5 10326.4 10454.3 9713.6 9861.2 9930.3 9980.9 10025.8 9263.5 9610.6 9621.3 9629.6 9641.0 6880.5 7272.7 7273.5 7299.8 7314.1 7122.0 7360.9 7391.5 7470.0 7563.7 08:34:16 INFO - PID 22510 | 08:34:16 INFO - PID 22510 | [#4] prototype.html Cycles:180 Average:5372.22 Median:5902.00 stddev:1498.41 (25.4%) stddev-sans-first:1457.05 08:34:16 INFO - PID 22510 | Values: 486.5 501.2 520.1 525.9 526.6 6032.5 6112.8 6113.1 6129.4 6136.0 5747.3 5981.0 6002.8 6011.2 6034.7 4819.3 4874.1 4897.1 4917.3 4958.4 4818.5 4839.9 4852.3 4886.2 4912.6 4844.1 4890.3 4903.8 4917.7 4922.2 4701.4 4819.9 4834.8 4896.4 4898.2 4357.9 4358.4 4388.2 4429.8 4445.1 5844.6 5856.9 5863.7 5891.8 5912.1 5532.4 5617.7 5638.7 5655.7 5658.5 6219.4 6319.5 6319.5 6332.7 6419.9 4792.9 4818.8 4820.4 4842.6 4849.3 6711.6 6789.4 6790.2 6804.7 6810.8 4964.1 4972.0 4995.6 5006.8 5011.8 4932.1 5023.1 5047.4 5050.4 5056.8 5472.9 5527.9 5558.8 5561.4 5589.9 6200.0 6254.0 6350.4 6409.7 6427.1 1698.8 1733.9 1736.5 1745.9 1757.1 1097.8 1203.5 1204.3 1210.5 1228.4 5350.9 5435.6 5493.7 5511.2 5653.3 6461.8 6462.8 6521.3 6551.4 6572.4 5429.8 5459.8 5507.1 5516.5 5541.4 3406.3 3441.0 3442.4 3468.7 3502.6 6246.7 6327.7 6381.8 6409.7 6413.7 6656.7 6751.6 6758.7 6769.3 6797.5 6385.4 6405.6 6407.1 6424.0 6462.0 6457.9 6459.7 6524.3 6548.1 6554.0 6466.9 6532.7 6541.0 6562.9 6571.7 6269.6 6323.3 6338.3 6390.0 6413.8 4881.9 4900.3 4918.4 4935.8 4939.4 6522.2 6547.9 6579.7 6590.9 6644.5 6287.3 6314.0 6366.2 6415.2 6426.6 6302.4 6308.7 6351.3 6365.2 6369.9 6242.3 6243.3 6246.2 6252.6 6329.2 6241.5 6309.3 6392.6 6401.7 6405.2 5722.7 6101.7 6132.9 6154.0 6186.3 08:34:16 INFO - PID 22510 | 08:34:16 INFO - PID 22510 | [#5] yui.html Cycles:180 Average:21077.71 Median:19128.21 stddev:27382.10 (143.2%) stddev-sans-first:27416.94 08:34:16 INFO - PID 22510 | Values: 886.8 900.9 911.8 921.6 929.8 18164.0 18249.4 18425.7 18464.6 18505.3 17768.2 18504.4 19566.3 19652.8 19799.0 1022.2 1047.4 1051.9 1058.4 1063.8 1060.7 1062.8 1062.9 1065.9 1070.4 1129.2 1132.2 1134.9 1134.9 1137.4 1009.5 1069.5 1097.1 1102.4 1103.4 796.3 797.9 798.3 799.0 799.3 18311.1 18577.7 18668.8 18870.5 18949.0 20116.3 20145.9 20405.2 20426.0 20702.1 15115.1 15182.3 15204.1 15242.0 15333.5 3364.2 3481.8 3511.9 3524.6 3538.7 101915.0 104254.5 110901.0 113032.4 116132.4 16542.2 16784.4 16841.6 16890.2 16940.7 27789.7 28819.1 29422.6 29610.6 29778.0 8617.6 8682.2 8713.9 8775.3 8830.1 4120.1 4196.6 4226.9 4245.2 4260.1 4933.4 5096.4 5124.4 5129.4 5135.5 1053.1 1084.9 1086.1 1105.8 1108.8 561.3 637.4 662.3 712.4 730.9 10785.3 11260.4 11476.1 11557.5 12477.5 616.9 639.7 650.5 669.1 711.2 5196.0 5276.6 5296.1 5397.9 5535.7 95019.5 95542.5 96279.9 96905.0 98456.5 78058.6 81424.2 83153.0 89482.6 89993.6 74958.6 77208.2 77578.6 77920.0 79394.2 11194.8 11376.3 11606.2 11611.2 11762.5 33292.5 35723.6 35827.0 36953.6 37177.6 42605.6 43829.3 44027.6 44293.3 45180.3 17204.9 17774.7 17967.7 18099.7 18371.4 19015.5 19240.9 19261.2 19511.5 19645.0 15500.8 16653.9 16743.6 16774.5 17001.7 16749.6 16829.2 18598.9 19419.9 19516.2 17033.2 17279.1 17407.9 17454.9 17848.6 12306.8 12386.1 12547.2 12598.2 13325.6 13249.3 13386.9 13710.7 13822.7 13953.2 08:34:16 INFO - PID 22510 | -------- Summary: end -------- 08:34:16 INFO - PID 22510 | 08:34:16 INFO - TEST-INFO | 22510: exit 0 08:34:16 INFO - TEST-OK | dromaeo_css | took 1110961ms 08:34:16 INFO - TEST-START | kraken 08:34:16 INFO - Initialising browser for kraken test... 08:34:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58334/getInfo.html -profile /tmp/tmpRmPsRl/profile 08:34:16 INFO - TEST-INFO | started process 22925 (/builds/slave/test/build/application/firefox/firefox http://localhost:58334/getInfo.html) 08:34:23 INFO - TEST-INFO | 22925: exit 0 08:34:23 INFO - Browser initialized. 08:34:23 INFO - Running cycle 1/1 for kraken test... 08:34:23 INFO - TEST-INFO | started process 23149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRmPsRl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:34:34 INFO - PID 23149 | RSS: Main: 169918464 08:34:34 INFO - PID 23149 | 08:34:37 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58334/tests/kraken/driver.html?testName=audio-beat-detection) 08:34:38 INFO - PID 23149 | RSS: Main: 176357376 08:34:38 INFO - PID 23149 | 08:34:40 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58334/tests/kraken/driver.html?testName=audio-dft) 08:34:41 INFO - PID 23149 | RSS: Main: 176214016 08:34:41 INFO - PID 23149 | 08:34:44 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58334/tests/kraken/driver.html?testName=audio-fft) 08:34:44 INFO - PID 23149 | RSS: Main: 170635264 08:34:44 INFO - PID 23149 | 08:34:47 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58334/tests/kraken/driver.html?testName=audio-oscillator) 08:34:47 INFO - PID 23149 | RSS: Main: 171253760 08:34:47 INFO - PID 23149 | 08:34:49 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58334/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:34:50 INFO - PID 23149 | RSS: Main: 171614208 08:34:50 INFO - PID 23149 | 08:34:53 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58334/tests/kraken/driver.html?testName=imaging-darkroom) 08:34:54 INFO - PID 23149 | RSS: Main: 174637056 08:34:54 INFO - PID 23149 | 08:34:57 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58334/tests/kraken/driver.html?testName=imaging-desaturate) 08:34:58 INFO - PID 23149 | RSS: Main: 176701440 08:34:58 INFO - PID 23149 | 08:35:00 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58334/tests/kraken/driver.html?testName=json-parse-financial) 08:35:01 INFO - PID 23149 | RSS: Main: 175689728 08:35:01 INFO - PID 23149 | 08:35:03 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58334/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:35:03 INFO - PID 23149 | RSS: Main: 175976448 08:35:03 INFO - PID 23149 | 08:35:05 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58334/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:35:06 INFO - PID 23149 | RSS: Main: 175738880 08:35:06 INFO - PID 23149 | 08:35:08 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58334/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:35:08 INFO - PID 23149 | RSS: Main: 176017408 08:35:08 INFO - PID 23149 | 08:35:11 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58334/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:35:12 INFO - PID 23149 | RSS: Main: 175325184 08:35:12 INFO - PID 23149 | 08:35:15 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58334/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:35:15 INFO - PID 23149 | RSS: Main: 175685632 08:35:15 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | Cycle 1(1): loaded http://localhost:58334/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58334/tests/kraken/driver.html?testName=ai-astar) 08:35:17 INFO - PID 23149 | RSS: Main: 176865280 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | __start_tp_report 08:35:17 INFO - PID 23149 | _x_x_mozilla_page_load 08:35:17 INFO - PID 23149 | _x_x_mozilla_page_load_details 08:35:17 INFO - PID 23149 | |i|pagename|runs| 08:35:17 INFO - PID 23149 | |0;ai-astar;101;109;110;107;112;108;108;109;110;111 08:35:17 INFO - PID 23149 | |1;audio-beat-detection;148;122;145;184;123;150;151;121;168;124 08:35:17 INFO - PID 23149 | |2;audio-dft;164;162;163;162;161;170;164;170;170;167 08:35:17 INFO - PID 23149 | |3;audio-fft;75;74;75;174;76;74;76;75;94;75 08:35:17 INFO - PID 23149 | |4;audio-oscillator;82;81;136;81;81;82;129;82;81;132 08:35:17 INFO - PID 23149 | |5;imaging-gaussian-blur;114;114;113;114;113;114;114;114;114;114 08:35:17 INFO - PID 23149 | |6;imaging-darkroom;150;149;150;149;149;149;148;149;150;149 08:35:17 INFO - PID 23149 | |7;imaging-desaturate;95;95;94;95;94;94;94;94;95;95 08:35:17 INFO - PID 23149 | |8;json-parse-financial;55;55;56;58;58;55;57;54;56;55 08:35:17 INFO - PID 23149 | |9;json-stringify-tinderbox;54;55;54;54;54;54;54;55;55;55 08:35:17 INFO - PID 23149 | |10;stanford-crypto-aes;78;80;80;78;77;81;87;77;76;78 08:35:17 INFO - PID 23149 | |11;stanford-crypto-ccm;127;124;129;130;137;132;136;130;125;159 08:35:17 INFO - PID 23149 | |12;stanford-crypto-pbkdf2;180;182;184;189;182;181;184;181;183;179 08:35:17 INFO - PID 23149 | |13;stanford-crypto-sha256-iterative;70;67;84;69;75;70;71;69;70;73 08:35:17 INFO - PID 23149 | __end_tp_report 08:35:17 INFO - PID 23149 | __start_cc_report 08:35:17 INFO - PID 23149 | _x_x_mozilla_cycle_collect,921 08:35:17 INFO - PID 23149 | __end_cc_report 08:35:17 INFO - PID 23149 | __startTimestamp1500219317776__endTimestamp 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | ------- Summary: start ------- 08:35:17 INFO - PID 23149 | Number of tests: 14 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#0] ai-astar Cycles:10 Average:108.50 Median:109.50 stddev:3.03 (2.8%) stddev-sans-first:1.58 08:35:17 INFO - PID 23149 | Values: 101.0 109.0 110.0 107.0 112.0 108.0 108.0 109.0 110.0 111.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:149.00 stddev:21.42 (14.4%) stddev-sans-first:22.66 08:35:17 INFO - PID 23149 | Values: 148.0 122.0 145.0 184.0 123.0 150.0 151.0 121.0 168.0 124.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#2] audio-dft Cycles:10 Average:165.30 Median:165.50 stddev:3.62 (2.2%) stddev-sans-first:3.81 08:35:17 INFO - PID 23149 | Values: 164.0 162.0 163.0 162.0 161.0 170.0 164.0 170.0 170.0 167.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#3] audio-fft Cycles:10 Average:86.80 Median:75.00 stddev:31.22 (41.6%) stddev-sans-first:32.82 08:35:17 INFO - PID 23149 | Values: 75.0 74.0 75.0 174.0 76.0 74.0 76.0 75.0 94.0 75.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:81.00 stddev:24.65 (30.4%) stddev-sans-first:25.56 08:35:17 INFO - PID 23149 | Values: 82.0 81.0 136.0 81.0 81.0 82.0 129.0 82.0 81.0 132.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:35:17 INFO - PID 23149 | Values: 114.0 114.0 113.0 114.0 113.0 114.0 114.0 114.0 114.0 114.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 08:35:17 INFO - PID 23149 | Values: 150.0 149.0 150.0 149.0 149.0 149.0 148.0 149.0 150.0 149.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 08:35:17 INFO - PID 23149 | Values: 95.0 95.0 94.0 95.0 94.0 94.0 94.0 94.0 95.0 95.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:1.37 (2.4%) stddev-sans-first:1.41 08:35:17 INFO - PID 23149 | Values: 55.0 55.0 56.0 58.0 58.0 55.0 57.0 54.0 56.0 55.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#9] json-stringify-tinderbox Cycles:10 Average:54.40 Median:54.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 08:35:17 INFO - PID 23149 | Values: 54.0 55.0 54.0 54.0 54.0 54.0 54.0 55.0 55.0 55.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:3.16 (4.0%) stddev-sans-first:3.32 08:35:17 INFO - PID 23149 | Values: 78.0 80.0 80.0 78.0 77.0 81.0 87.0 77.0 76.0 78.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#11] stanford-crypto-ccm Cycles:10 Average:132.90 Median:131.00 stddev:10.09 (7.7%) stddev-sans-first:10.48 08:35:17 INFO - PID 23149 | Values: 127.0 124.0 129.0 130.0 137.0 132.0 136.0 130.0 125.0 159.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.50 Median:182.50 stddev:2.80 (1.5%) stddev-sans-first:2.82 08:35:17 INFO - PID 23149 | Values: 180.0 182.0 184.0 189.0 182.0 181.0 184.0 181.0 183.0 179.0 08:35:17 INFO - PID 23149 | 08:35:17 INFO - PID 23149 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:70.50 stddev:4.83 (6.8%) stddev-sans-first:5.07 08:35:17 INFO - PID 23149 | Values: 70.0 67.0 84.0 69.0 75.0 70.0 71.0 69.0 70.0 73.0 08:35:17 INFO - PID 23149 | -------- Summary: end -------- 08:35:17 INFO - PID 23149 | 08:35:18 INFO - PID 23149 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 08:35:18 INFO - TEST-INFO | 23149: exit 0 08:35:18 INFO - TEST-OK | kraken | took 61821ms 08:35:18 INFO - SUITE-END | took 1172s 08:35:18 INFO - Completed test suite (00:19:33) 08:35:18 INFO - javascript benchmark 08:35:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8264.714311186552, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3062.800917940334, 3065.6474505431884, 3086.074177746677, 3095.14883407063, 3124.3751249750053, 5845.853853653655, 5890.793822216223, 5911.763529458827, 5938.940610593892, 5993.400659934005, 5969.044952807551, 5988.940110598893, 5993.370696076914, 5993.850153746159, 6012.5791194616395, 5478.233047373371, 5490.066688662937, 5496.7526461309135, 5500.092484740013, 5508.834734957958, 5493.395726470085, 5500.3949565547755, 5502.669839809604, 5503.972480137593, 5506.724663766816, 5501.724913754317, 5506.284183056197, 5507.201455788928, 5508.338999320087, 5513.228148059275, 5506.256655351556, 5513.58648101394, 5522.585806064561, 5527.972360138214, 5534.363548191949, 5490.835274941758, 5515.283013208297, 5519.69641669708, 5521.199426083235, 5544.306961629796, 5966.552508561832, 5990.2212712347555, 6042.909356359658, 6049.758009679652, 6075.453209211192, 6334.113224148623, 6374.394432528902, 6379.362063793584, 6460.838479038061, 6465.191851018622, 6464.870702585922, 6490.4807615390655, 6508.0, 6522.249941256769, 6533.477321814244, 6005.429484198994, 6047.304559975615, 6055.636661800306, 6064.332923378425, 6068.908966365508, 5766.682832444172, 6570.704318305736, 6660.267370589231, 6679.499037572202, 6730.798076057726, 6312.652804095727, 6326.240851097806, 6340.8414789629705, 6413.935860641427, 6417.711202995783, 6331.746730130743, 6436.16329877113, 6443.935560644427, 6460.224773027267, 6539.280679125292, 6241.937580624226, 6312.495000399957, 6357.523185761086, 6410.391012853865, 6451.096846441502, 5522.0, 6340.461060809791, 6368.42684158428, 6374.585651932703, 6587.0, 5091.592672586185, 5206.76569554371, 5234.528892399702, 5288.286081379041, 5312.814051508256, 4490.124425737015, 4491.663125265618, 4495.9325610115875, 4506.2564676828615, 4509.932351014738, 5533.916991245134, 5548.69482178476, 5549.805756798573, 5557.916631250535, 5567.498925096761, 6433.774817881352, 6487.967560162169, 6533.444657204191, 6533.67331633426, 6543.574667646683, 5460.235567020621, 5482.095454250004, 5502.9174562381595, 5554.1946417770005, 5577.665340079527, 5648.265725455664, 5709.942900570941, 5776.249087618667, 5802.88394232113, 5811.244538210006, 6024.57827952048, 6135.263768347827, 6163.876722465526, 6164.352742961924, 6191.349908259658, 6378.553501254961, 6425.935740642721, 6492.091107244895, 6504.349565043647, 6508.186476690414, 6088.360722124113, 6157.630542167484, 6161.784337548164, 6193.0, 6265.749370025148, 6364.777232797015, 6378.712957916812, 6423.614583125028, 6457.741690332522, 6482.060101285381, 6477.1579694639395, 6504.804855854332, 6577.769778057745, 6588.934110658832, 6615.17310336208, 6165.0, 6264.185655864891, 6267.279262884645, 6298.874022519615, 6382.6170429774365, 6252.874482593177, 6271.62370257786, 6277.246730392382, 6281.560290779602, 6291.685415729286, 6114.449699527064, 6136.140940268277, 6147.139400483847, 6167.074938759042, 6194.194754681863, 5562.922626940867, 6307.432331090224, 6359.618422894641, 6392.328805475544, 6456.128422663069, 6140.631562106288, 6159.62441314554, 6243.157173781481, 6246.219222597176, 6264.21697287839], "value": 5868.733150683253, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.6149782861827, 791.1100012485954, 821.3757544266358, 841.0706169682501, 848.872669099635, 39946.600533994686, 41108.58891411085, 41255.793721031354, 41272.17455650885, 41316.38025429621, 37335.253294934155, 37492.81253593735, 37739.433908491395, 37785.24429511415, 37847.0, 6659.300773418782, 6718.865622687544, 6804.557703749265, 6809.250982391958, 6821.147356580427, 6613.570117942319, 6768.966155169217, 6790.9320906791045, 6791.592504449724, 6808.319168083177, 6721.462283017371, 6755.560888542253, 6781.355771201728, 6794.218664853531, 6800.251972283069, 6634.038064480647, 6709.731610735565, 6732.764353247613, 6741.494387920926, 6747.325267473262, 6132.172156758825, 6137.048757442603, 6179.938200618026, 6186.19579454668, 6204.565680402374, 32749.345013099606, 33095.0, 33133.502997455056, 33179.0, 33191.17022074467, 3360.462326027824, 3375.8143302118374, 3399.762016658835, 3400.778949368283, 3403.0471467989005, 26478.0, 30502.54246186309, 31310.0, 31383.84308078445, 31465.21336966571, 4936.456989731127, 4948.282499037619, 4960.032793605248, 4969.27945447908, 5010.148274793294, 186777.0, 188880.0, 189171.05414472977, 191667.0, 193459.0, 46681.29978050332, 47543.76228118906, 47745.0, 47856.76071619689, 48186.0, 50622.0, 50678.0, 50684.74657626688, 50764.23853642198, 50784.49215507872, 15861.444849430445, 16069.839301606835, 16123.355065797472, 16128.193590320438, 16238.0, 13352.933235333956, 13365.866341336656, 13387.0, 13404.597862064154, 13404.932975335061, 14469.27653617336, 14499.420023199165, 14579.635509112357, 14583.126796867296, 14649.414023438943, 5640.5769567281795, 5751.376632721359, 5755.568332375088, 5804.448577385226, 5906.645601263938, 981.6613268422383, 994.4132961552574, 1016.9389836609827, 1037.6938803053117, 1042.103790739963, 22139.889300553394, 22605.849907740307, 22813.885930570243, 23349.416264593183, 23578.29265122049, 1090.7218659241842, 1103.6137351926761, 1127.0814286356592, 1131.5473810475876, 1133.0822034152363, 11903.226290291102, 12021.879781202077, 12164.965977891978, 12375.443105060294, 12440.253584784941, 215203.0, 224144.0, 224203.0, 224267.0, 225332.0, 51263.0, 51544.0, 51666.741666291426, 51843.48156518462, 51866.22200666993, 1449.6013596260902, 1455.4396557325294, 1485.5320574019365, 1488.5608745420127, 1489.4861272860846, 22020.56866303685, 22058.33824985253, 22591.20930767415, 22623.98192081393, 23113.537729244985, 35587.0, 35641.46537801987, 35866.82066589755, 35868.282634346644, 36014.27971440504, 193259.0, 193968.0301598483, 194370.0, 194427.0, 194780.0, 33797.155071123416, 35433.64566354406, 35975.0, 37747.43378849374, 37785.433218502294, 46020.07959840851, 46668.299975501075, 46956.295655564514, 47313.763431182626, 47582.0, 31438.0, 32765.017049488553, 33515.4972675415, 33917.321653566294, 34050.659493404746, 32499.0, 33097.83451082825, 33261.667383325854, 33352.0, 33742.32515349728, 36725.63274367222, 36942.0, 37802.43296350612, 38090.238195236496, 38146.0, 31154.0, 31579.0, 32522.186945326557, 32599.348013039133, 32883.83558082194, 23531.41171470727, 23589.174378896656, 23596.31909804967, 23824.88087559551, 24418.389540260923], "value": 16682.742095276764, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.5870173475099, 579.6840721806612, 583.8657108864958, 598.267122774601, 609.3662590905459, 21950.670739938894, 22783.202587909385, 22951.08195672175, 23101.0, 23125.537489250204, 27078.59382109265, 27478.725212747868, 27636.309092272702, 27641.447171056567, 27653.0, 6544.967275163617, 6756.290589488107, 6763.357481039294, 6782.050512928194, 6809.829754256134, 6193.628382297076, 6332.366763323676, 6490.0, 6605.0422688710105, 6631.0, 6443.130177426057, 6525.902111468332, 6547.181602299713, 6549.214094308666, 6558.8688226235445, 6522.836929076763, 6527.0, 6528.869422611545, 6532.314107018766, 6536.379043990813, 6100.206973093514, 6109.144719739195, 6109.5723299369065, 6110.236220472441, 6117.5717699761035, 28160.718392816016, 29128.0, 29685.70314296851, 29755.10734677963, 31091.689083109333, 1898.5443493561584, 1936.6320399124122, 1941.3981665683684, 1951.1219951021956, 1959.0, 19072.0, 19644.607107857904, 19646.312379066807, 19650.70523942142, 19671.114799834042, 2335.859848409101, 2352.0, 2363.420961864337, 2401.591729406005, 2430.2466235466886, 133853.3307333467, 138391.0, 138421.99999999898, 138596.0, 138626.30686846602, 14584.854151458561, 15309.311081001382, 15342.769858452131, 15463.149526775911, 15695.764563531557, 24038.759612404003, 24067.759322406553, 24092.39769005789, 24193.63709544358, 24216.636750448757, 10172.389656620626, 10261.640842570474, 10364.274500785023, 10400.427976461368, 10684.412357320422, 9624.51877406127, 9649.469279189712, 9658.130768230892, 9670.323077384515, 9867.11195992364, 8624.0, 9317.953410232905, 9348.439093654402, 9423.623055077856, 9449.480278584606, 2684.597310403455, 2739.643846300008, 2803.9859800701274, 2889.4510043091746, 2900.390917907221, 955.5031383680447, 958.6021800952666, 974.3861367338532, 985.9211263098935, 993.1210878372548, 19820.20719171217, 19842.206311747657, 20155.798442015686, 20515.794842051688, 20638.38084857457, 1009.3388830316155, 1016.4307987527009, 1046.795874804406, 1047.2878442658912, 1054.583439541377, 10991.907583707412, 11184.720381990515, 11205.495752691148, 11396.259243149168, 11397.487113079931, 411583.0, 427958.0, 437851.0, 446864.0, 448072.0, 109093.0, 111309.0, 115571.0, 116154.419227905, 116293.0, 1161.7153797319543, 1192.099964526794, 1210.818377243403, 1219.3354621731178, 1221.82283568882, 16665.083420411993, 17754.467365978526, 17984.460466186036, 18384.816151838844, 18718.812811871707, 51842.0, 56610.15084773649, 57028.0, 58915.0, 58968.0, 68947.3105268961, 69273.3072669287, 69440.65279673772, 69498.30501694918, 70263.0, 11919.0, 14670.0, 15086.698266034837, 15099.169545675093, 15107.39570417171, 17857.28570857203, 21259.0, 23668.408289792893, 24019.879900600386, 24127.51744965126, 18382.540436489195, 18476.907615462373, 18611.441656750318, 18876.905615471835, 18934.715979260596, 15996.840031599535, 16348.918255408647, 16511.33954641801, 16986.320547177973, 17127.743083854002, 18805.0, 18919.810801891806, 18934.715979260047, 18986.905065474584, 19179.328723495168, 14740.631484212981, 14960.40158393652, 15329.77005344943, 15341.769873451683, 15358.308876100378, 19140.330088447394, 19232.23071077141, 19315.130819112892, 19324.8067519323, 19559.0], "value": 12027.84638399526, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.359221166052, 1422.374155371637, 1434.6126545832633, 1435.1532595768495, 1447.8117844680182, 13288.601341959744, 13824.93087534561, 13830.585082447518, 13852.0, 13892.30538473076, 12937.353132343393, 13135.6716082098, 13207.273599952, 13265.40305686244, 13293.468261269538, 2696.0968075694664, 2773.4730401223806, 2773.681026681927, 2774.8057635965492, 2775.9028434004813, 2654.2568080937367, 2727.1136880513814, 2727.359070618403, 2730.5358089124898, 2734.4667789781, 2236.586231547169, 2676.050002249193, 2717.1440996086267, 2736.343277613378, 2742.2184677366954, 2278.646809744484, 2281.3726225288015, 2298.0118549023996, 2599.896004159831, 2702.3244188952763, 2632.06561670608, 2660.1221596872983, 2663.893444262227, 2666.1068542038443, 2668.146193218171, 13115.0, 13295.933520332434, 13345.73308533824, 13354.26551539665, 13355.131916425402, 7353.7058517659225, 7617.0, 7697.268759467842, 7710.421718371144, 7717.807054823618, 4912.14037543432, 5093.159628661267, 5249.02892964799, 5280.419153893069, 5307.203919412081, 1836.1370156026646, 1851.787044489874, 1879.783824860145, 1924.7594050743658, 1928.2865339824214, 22314.55370892573, 22430.214942477098, 22569.54860902789, 22584.774152258433, 22620.88689556558, 6326.68366581669, 6465.224173099258, 6466.256380516301, 6537.901931471032, 6629.751421803282, 15507.147106909002, 15624.76562851558, 15672.05967641945, 15682.137482438347, 15691.450799221973, 7395.371393431618, 7418.777436676908, 7509.812254693567, 7543.622818859035, 7556.622168891534, 6900.137482814648, 6940.167179938439, 6989.126359205099, 7011.26381729911, 7088.610126442992, 5044.9495505045215, 5151.948480515147, 5163.432022477525, 5230.0, 5316.7075810830775, 1484.9925750371178, 1489.791429199913, 1492.2911616981846, 1517.9089254644537, 1539.507357645565, 891.5809569502453, 899.2176806178667, 908.3323757038571, 922.9169374756304, 926.0693003531408, 7062.858742825218, 7092.4326053915565, 7192.892106618405, 7198.0, 7212.3148300911325, 708.3235510087853, 739.2755100002028, 991.9355241909398, 1025.29254814178, 1032.6179313653995, 4822.95177048225, 5052.722100284446, 5148.794048238104, 5186.4035635902355, 5189.792408303701, 18088.728669069973, 18956.905215473835, 19043.238270469024, 19049.047547622842, 19069.237230510626, 18212.18045187943, 18501.53746156357, 18548.0, 18834.0, 18989.715154272697, 10634.946825265824, 10798.946005269923, 10803.567857285778, 10870.61952831647, 10917.836232456519, 7576.848463030598, 7943.364530837634, 7986.84026319482, 8103.594820258845, 8494.32045436376, 15726.606834829221, 15862.127582983047, 16421.17894105266, 16425.507234782977, 16588.66822663516, 15967.041977481389, 16506.58733531671, 16800.0, 16801.0, 17004.489865304062, 11206.0, 11540.480678369326, 11551.249168804, 11557.942210288895, 11647.242929209575, 12809.103362764517, 12933.353332333158, 12953.158044727063, 12977.221366718028, 13000.024998124989, 9910.058544438267, 10076.445795481319, 10307.536160872929, 10326.432046237529, 10454.32046916948, 9713.611455541983, 9861.211103111877, 9930.25523085757, 9980.900190998287, 10025.799484010426, 9263.490508022123, 9610.567524461276, 9621.326507144433, 9629.566669499749, 9641.0, 6880.518363714492, 7272.745453909088, 7273.454490913097, 7299.817504562216, 7314.08573928259, 7122.0, 7360.889586656097, 7391.519551229153, 7470.0, 7563.7352692655495], "value": 6288.321691617135, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.5232072568882, 501.2481278082877, 520.0976306108901, 525.90270799902, 526.6024151765416, 6032.547558933081, 6112.829945369396, 6113.065728450347, 6129.38706129388, 6136.018237082068, 5747.281589801275, 5980.970095149518, 6002.789902353418, 6011.188489553909, 6034.698265086739, 4819.2771084337455, 4874.122657921564, 4897.094037603055, 4917.3361596184595, 4958.380202474691, 4818.542238487338, 4839.903201935959, 4852.272159176134, 4886.242632391985, 4912.606991440676, 4844.07962487128, 4890.315355850184, 4903.754812259391, 4917.680350777206, 4922.237053256733, 4701.388819453467, 4819.927701084486, 4834.806607735686, 4896.4124305083615, 4898.191798353268, 4357.934630980538, 4358.389825424443, 4388.166248412791, 4429.756363400012, 4445.110977804452, 5844.620099693507, 5856.853578660525, 5863.677497737623, 5891.849474941659, 5912.142739302777, 5532.419095994943, 5617.747201375907, 5638.731285460739, 5655.660660360391, 5658.462446067617, 6219.440250377488, 6319.494440444753, 6319.526035547352, 6332.68336583169, 6419.87160256797, 4792.856214313541, 4818.7831547580445, 4820.373351464323, 4842.588379987706, 4849.321095046696, 6711.56374835639, 6789.355011273921, 6790.185177778649, 6804.659767011679, 6810.829729256809, 4964.131277026506, 4972.0, 4995.625328100407, 5006.799728010838, 5011.824586139468, 4932.112219800399, 5023.070731914534, 5047.369078865142, 5050.37380562113, 5056.772445239973, 5472.91790623141, 5527.889442211133, 5558.77764889408, 5561.416051314553, 5589.944100559023, 6200.0, 6254.0, 6350.364963503613, 6409.692510696145, 6427.100205971168, 1698.7791587093598, 1733.8643188711414, 1736.461696873973, 1745.8690598205185, 1757.0847775924303, 1097.8133717268083, 1203.5366383942246, 1204.3376143121247, 1210.4734440518403, 1228.42264135856, 5350.866228344323, 5435.646682965595, 5493.670379777226, 5511.173324001352, 5653.265075540154, 6461.8061458156335, 6462.838429039311, 6521.250056243593, 6551.377619126175, 6572.408483236436, 5429.782808687608, 5459.836204913859, 5507.146392309199, 5516.475934786189, 5541.362743284493, 3406.2846802171325, 3440.9655903440644, 3442.4492081266876, 3468.670476304747, 3502.614712381636, 6246.6876656167005, 6327.746890124344, 6381.8085457436355, 6409.743610255631, 6413.711382987777, 6656.73373065072, 6751.594904305757, 6758.715843989627, 6769.295339738933, 6797.456203503708, 6385.361463853577, 6405.647689377036, 6407.103005579223, 6423.967880160662, 6462.029081130207, 6457.870842583028, 6459.709313080829, 6524.347565243438, 6548.1487406638735, 6554.0, 6466.870662586816, 6532.706028228646, 6541.0, 6562.868742625025, 6571.671416429255, 6269.62382257066, 6323.336049714898, 6338.334474880256, 6390.0, 6413.8396540085005, 4881.877953051202, 4900.30939325932, 4918.409790825124, 4935.827246046371, 4939.357883475125, 6522.21733391996, 6547.901781473377, 6579.703913323816, 6590.90113648286, 6644.534882558174, 6287.321916678794, 6314.0, 6366.2360516736435, 6415.230172379158, 6426.550141490051, 6302.361386039571, 6308.684565771601, 6351.301356850835, 6365.236171659245, 6369.9363006371195, 6242.313345531897, 6243.344448832807, 6246.156768836149, 6252.5935814171935, 6329.208848893888, 6241.500679945684, 6309.274433440213, 6392.616443013434, 6401.743930242925, 6405.19935008124, 5722.742476588647, 6101.664408457577, 6132.9080063799975, 6154.0, 6186.257649082139], "value": 4934.184821994262, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [886.7916039730662, 900.8648702694597, 911.7766147293914, 921.6451666108546, 929.7768535551468, 18163.99999999998, 18249.36127235547, 18425.723614145765, 18464.630707385844, 18505.2597896084, 17768.200430980603, 18504.352347667835, 19566.31517896874, 19652.803471965275, 19799.0, 1022.1771473963456, 1047.3611097230682, 1051.8790339111024, 1058.4443167337133, 1063.8457423673563, 1060.6923992042298, 1062.8140075486797, 1062.8671416072953, 1065.9307145035548, 1070.4273213830602, 1129.1531351486385, 1132.1735133352659, 1134.9489272982694, 1134.9489272982735, 1137.385811661706, 1009.5053423822311, 1069.4652673663168, 1097.0839349143475, 1102.4377567440663, 1103.382106020627, 796.2992566541407, 797.9002624671916, 798.2695833312512, 799.0371602219312, 799.3245707377257, 18311.08444577779, 18577.651666533366, 18668.81331186698, 18870.528236794053, 18949.0, 20116.29592964239, 20145.8992705037, 20405.183792648277, 20425.978701064887, 20702.068406921724, 15115.093094414371, 15182.316795744218, 15204.087754734752, 15242.009269397453, 15333.539993800205, 3364.192593777476, 3481.8259087045544, 3511.894643160709, 3524.559430071241, 3538.716902647782, 101915.0, 104254.47872760663, 110901.0, 113032.43483782529, 116132.41933790362, 16542.172891355385, 16784.41254556085, 16841.578960526083, 16890.155492225585, 16940.661186776197, 27789.722102778713, 28819.135425937257, 29422.558661620093, 29610.555841662394, 29778.0, 8617.612207450555, 8682.175193356621, 8713.869291960626, 8775.254103401156, 8830.116988301119, 4120.114175452232, 4196.559361267084, 4226.873193804191, 4245.1934132514725, 4260.147970405931, 4933.407991041026, 5096.362954630671, 5124.436312005676, 5129.410117836422, 5135.512126347991, 1053.0680347892016, 1084.9186311026547, 1086.0931122512632, 1105.834124881258, 1108.8004159251411, 561.3488353509908, 637.4072112935015, 662.3012721578781, 712.4264966701751, 730.8720973829559, 10785.298955567863, 11260.43697815106, 11476.081913446904, 11557.479913403917, 12477.5008999639, 616.921561408577, 639.7409049334934, 650.5088658063132, 669.1468377818223, 711.1714852197235, 5195.986782577361, 5276.604254680914, 5296.09966305729, 5397.946020539823, 5535.6955367455175, 95019.52490237505, 95542.52228738951, 96279.87213458725, 96905.0, 98456.50771746096, 78058.60970695224, 81424.18575814285, 83153.0, 89482.55258723664, 89993.55003224942, 74958.62520687471, 77208.22791772123, 77578.61210693911, 77920.0, 79394.20605793867, 11194.776104477702, 11376.260543064674, 11606.187566870238, 11611.245269057823, 11762.529498819951, 33292.50061249132, 35723.64276357307, 35827.0, 36953.630463695015, 37177.6282237185, 42605.57394426016, 43829.34255986226, 44027.559724402345, 44293.33559996667, 45180.322295166254, 17204.91397543004, 17774.733378999066, 17967.73048404301, 18099.728504072187, 18371.35700250538, 19015.52461188481, 19240.903795481492, 19261.22955081837, 19511.512212194808, 19645.017749112772, 15500.84499154994, 16653.91673041627, 16743.581410464347, 16774.49676509707, 17001.744973825156, 16749.581260468585, 16829.242684079, 18598.907005464887, 19419.902900485406, 19516.21935122579, 17033.233504492575, 17279.136043198043, 17407.91296043512, 17454.91272543629, 17848.643027139125, 12306.81539776922, 12386.106328714362, 12547.184433011826, 12598.244105353708, 13325.600231993056, 13249.271290079138, 13386.866131338562, 13710.65723356924, 13822.65443363924, 13953.232572208626], "value": 8721.466477464606, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1535.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 109.0, 110.0, 107.0, 112.0, 108.0, 108.0, 109.0, 110.0, 111.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 122.0, 145.0, 184.0, 123.0, 150.0, 151.0, 121.0, 168.0, 124.0], "value": 143.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 162.0, 161.0, 170.0, 164.0, 170.0, 170.0, 167.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 174.0, 76.0, 74.0, 76.0, 75.0, 94.0, 75.0], "value": 86.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 136.0, 81.0, 81.0, 82.0, 129.0, 82.0, 81.0, 132.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 149.0, 149.0, 149.0, 148.0, 149.0, 150.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 58.0, 58.0, 55.0, 57.0, 54.0, 56.0, 55.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0, 55.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 80.0, 78.0, 77.0, 81.0, 87.0, 77.0, 76.0, 78.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 124.0, 129.0, 130.0, 137.0, 132.0, 136.0, 130.0, 125.0, 159.0], "value": 132.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 182.0, 184.0, 189.0, 182.0, 181.0, 184.0, 181.0, 183.0, 179.0], "value": 182.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 84.0, 69.0, 75.0, 70.0, 71.0, 69.0, 70.0, 73.0], "value": 71.8, "unit": "score"}]}]} 08:35:18 INFO - Return code: 0 08:35:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:35:19 INFO - # TBPL SUCCESS # 08:35:19 INFO - Running post-action listener: _package_coverage_data 08:35:19 INFO - Running post-action listener: _resource_record_post_action 08:35:19 INFO - [mozharness: 2017-07-16 15:35:19.072265Z] Finished run-tests step (success) 08:35:19 INFO - Running post-run listener: _resource_record_post_run 08:35:19 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 434483200; Read time: 12; Write time: 281404 08:35:19 INFO - TinderboxPrint: CPU usage
13.1% 08:35:19 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:35:19 INFO - TinderboxPrint: I/O write bytes / time
434,483,200 / 281,404 08:35:19 INFO - TinderboxPrint: CPU idle
8,252.5 (86.9%) 08:35:19 INFO - TinderboxPrint: CPU user
1,197.2 (12.6%) 08:35:19 INFO - TinderboxPrint: Swap in / out
0 / 0 08:35:19 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107200512; Read time: 0; Write time: 79440 08:35:19 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:35:19 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 327282688; Read time: 0; Write time: 201964 08:35:19 INFO - Running post-run listener: _upload_blobber_files 08:35:19 INFO - Blob upload gear active. 08:35:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:35:19 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 08:35:19 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'] 08:35:19 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 08:35:20 INFO - Traceback (most recent call last): 08:35:20 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 08:35:20 INFO - main() 08:35:20 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 08:35:20 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 08:35:20 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 08:35:20 INFO - return set(response.json().get('whitelist', [])) 08:35:20 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 08:35:20 INFO - return json.loads(self.text or self.content, **kwargs) 08:35:20 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 08:35:20 INFO - return _default_decoder.decode(s) 08:35:20 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 08:35:20 INFO - obj, end = self.raw_decode(s) 08:35:20 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 08:35:20 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 08:35:20 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 08:35:20 ERROR - Return code: 1 08:35:20 INFO - Setting buildbot property blobber_files to {} 08:35:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:35:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:35:20 INFO - Contents: 08:35:20 INFO - blobber_files:{} 08:35:20 INFO - Running post-run listener: copy_logs_to_upload_dir 08:35:20 INFO - Copying logs to upload dir... 08:35:20 INFO - mkdir: /builds/slave/test/build/upload/logs 08:35:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.281541 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-07-16 08:35:18.743287) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-16 08:35:18.747132) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/KcZaFrWRQQ6ypWymSEoK1w/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-16 08:35:18.805272) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:35:18.805861) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1500206488.500803-1067122566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 08:35:18.858995) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-16 08:35:18.859657) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-16 08:35:18.860267) ========= ========= Total master_lag: 0.17 =========