builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-067 starttime: 1496294126.92 results: success (0) revision: c81fbc7bbc63ccde05ba7bc132b9cbd57f606da0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:26.915552) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:26.915980) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:26.916344) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:26.936603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:26.936910) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-31 22:15:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2017-05-31 22:15:27 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.159320 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:27.108730) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:27.109033) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015308 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:27.160759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:27.161053) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c81fbc7bbc63ccde05ba7bc132b9cbd57f606da0 --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 c81fbc7bbc63ccde05ba7bc132b9cbd57f606da0 --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=e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-31 22:15:27,845 truncating revision to first 12 chars 2017-05-31 22:15:27,845 Setting DEBUG logging. 2017-05-31 22:15:27,845 attempt 1/10 2017-05-31 22:15:27,845 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c81fbc7bbc63?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-31 22:15:28,287 unpacking tar archive at: mozilla-central-c81fbc7bbc63/testing/mozharness/ program finished with exit code 0 elapsedTime=0.820093 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:27.992827) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:27.993091) ========= script_repo_revision: c81fbc7bbc63ccde05ba7bc132b9cbd57f606da0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:27.993453) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:27.993718) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 22:15:28.004684) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 56 secs) (at 2017-05-31 22:15:28.004944) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:15:28 INFO - MultiFileLogger online at 20170531 22:15:28 in /builds/slave/test 22:15:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 22:15:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:15:28 INFO - {'append_to_log': False, 22:15:28 INFO - 'base_work_dir': '/builds/slave/test', 22:15:28 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 22:15:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:15:28 INFO - 'branch': 'Firefox-Non-PGO', 22:15:28 INFO - 'buildbot_json_path': 'buildprops.json', 22:15:28 INFO - 'config_files': ('talos/linux_config.py',), 22:15:28 INFO - 'default_actions': ('clobber', 22:15:28 INFO - 'read-buildbot-config', 22:15:28 INFO - 'download-and-extract', 22:15:28 INFO - 'populate-webroot', 22:15:28 INFO - 'create-virtualenv', 22:15:28 INFO - 'install', 22:15:28 INFO - 'setup-mitmproxy', 22:15:28 INFO - 'run-tests'), 22:15:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:15:28 INFO - 'download_minidump_stackwalk': True, 22:15:28 INFO - 'download_symbols': 'ondemand', 22:15:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:15:28 INFO - 'tooltool.py': '/tools/tooltool.py', 22:15:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:15:28 INFO - '/tools/misc-python/virtualenv.py')}, 22:15:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:15:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:15:28 INFO - 'gecko_profile': False, 22:15:28 INFO - 'gecko_profile_interval': 0, 22:15:28 INFO - 'installer_path': 'installer.exe', 22:15:28 INFO - 'log_level': 'info', 22:15:28 INFO - 'log_name': 'talos', 22:15:28 INFO - 'log_to_console': True, 22:15:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:15:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:15:28 INFO - 'opt_config_files': (), 22:15:28 INFO - 'pip_index': False, 22:15:28 INFO - 'suite': 'dromaeojs-e10s', 22:15:28 INFO - 'system_bits': '32', 22:15:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:15:28 INFO - 'title': 'talos-linux64-ix-067', 22:15:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:15:28 INFO - 'use_talos_json': True, 22:15:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:15:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:15:28 INFO - 'work_dir': 'build'} 22:15:28 INFO - [mozharness: 2017-06-01 05:15:28.787734Z] Running clobber step. 22:15:28 INFO - Running pre-action listener: _resource_record_pre_action 22:15:28 INFO - Running main action method: clobber 22:15:28 INFO - rmtree: /builds/slave/test/build 22:15:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:15:29 INFO - Running post-action listener: _resource_record_post_action 22:15:29 INFO - [mozharness: 2017-06-01 05:15:29.794173Z] Finished clobber step (success) 22:15:29 INFO - [mozharness: 2017-06-01 05:15:29.794273Z] Running read-buildbot-config step. 22:15:29 INFO - Running pre-action listener: _resource_record_pre_action 22:15:29 INFO - Running main action method: read_buildbot_config 22:15:29 INFO - Using buildbot properties: 22:15:29 INFO - { 22:15:29 INFO - "project": "", 22:15:29 INFO - "product": "firefox", 22:15:29 INFO - "who": "philringnalda@gmail.com", 22:15:29 INFO - "installer_path": "public/build/target.tar.bz2", 22:15:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 22:15:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 22:15:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 22:15:29 INFO - "stage_platform": "linux64", 22:15:29 INFO - "basedir": "/builds/slave/test", 22:15:29 INFO - "buildnumber": 920, 22:15:29 INFO - "platform": "ubuntu64_hw", 22:15:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:15:29 INFO - "slavebuilddir": "test", 22:15:29 INFO - "taskId": "NkXQznEpSbCB2CtJMVyZsA", 22:15:29 INFO - "branch": "mozilla-central", 22:15:29 INFO - "script_repo_revision": "production", 22:15:29 INFO - "revision": "c81fbc7bbc63ccde05ba7bc132b9cbd57f606da0", 22:15:29 INFO - "slavename": "talos-linux64-ix-067", 22:15:29 INFO - "repo_path": "mozilla-central" 22:15:29 INFO - } 22:15:29 INFO - Finding installer, test and symbols from parent task. 22:15:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NkXQznEpSbCB2CtJMVyZsA'}, attempt #1 22:15:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NkXQznEpSbCB2CtJMVyZsA'}, attempt #1 22:15:30 INFO - Task dependencies: QZZGBCr1SxyzA6SLRv1CMg 22:15:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg'}, attempt #1 22:15:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.tar.bz2 22:15:31 INFO - Set test_url: https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/test_packages.json 22:15:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.crashreporter-symbols.zip 22:15:31 INFO - Running post-action listener: _resource_record_post_action 22:15:31 INFO - [mozharness: 2017-06-01 05:15:31.133949Z] Finished read-buildbot-config step (success) 22:15:31 INFO - [mozharness: 2017-06-01 05:15:31.134170Z] Running download-and-extract step. 22:15:31 INFO - Running pre-action listener: _resource_record_pre_action 22:15:31 INFO - Running main action method: download_and_extract 22:15:31 INFO - mkdir: /builds/slave/test/build/tests 22:15:31 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')]} 22:15:31 INFO - trying https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.test_packages.json 22:15:31 INFO - Downloading https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 22:15:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 22:15:31 INFO - Downloaded 986 bytes. 22:15:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 22:15:31 INFO - Using the following test package requirements: 22:15:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 22:15:31 INFO - u'common': [u'target.common.tests.zip'], 22:15:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 22:15:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 22:15:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 22:15:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 22:15:31 INFO - u'mozbase': [u'target.common.tests.zip'], 22:15:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 22:15:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 22:15:31 INFO - u'web-platform': [u'target.common.tests.zip', 22:15:31 INFO - u'target.web-platform.tests.zip'], 22:15:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 22:15:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 22:15:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.common.tests.zip 22:15:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.common.tests.zip'}, attempt #1 22:15:31 INFO - Fetch https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.common.tests.zip into memory 22:15:34 INFO - Content-Length response header: 63533791 22:15:34 INFO - Bytes received: 63533791 22:15:45 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 22:15:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.common.tests.zip 22:15:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.common.tests.zip'}, attempt #1 22:15:45 INFO - Fetch https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.common.tests.zip into memory 22:15:47 INFO - Content-Length response header: 63533791 22:15:47 INFO - Bytes received: 63533791 22:16:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.talos.tests.zip 22:16:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 22:16:00 INFO - Fetch https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.talos.tests.zip into memory 22:16:01 INFO - Content-Length response header: 14447425 22:16:01 INFO - Bytes received: 14447425 22:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:01 INFO - trying https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.tar.bz2 22:16:01 INFO - Downloading https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 22:16:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 22:16:03 INFO - Downloaded 54091460 bytes. 22:16:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.tar.bz2 22:16:03 INFO - mkdir: /builds/slave/test/properties 22:16:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:16:03 INFO - Writing to file /builds/slave/test/properties/build_url 22:16:03 INFO - Contents: 22:16:03 INFO - build_url:https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.tar.bz2 22:16:03 INFO - Running post-action listener: _resource_record_post_action 22:16:03 INFO - Running post-action listener: set_extra_try_arguments 22:16:03 INFO - [mozharness: 2017-06-01 05:16:03.104012Z] Finished download-and-extract step (success) 22:16:03 INFO - [mozharness: 2017-06-01 05:16:03.104238Z] Running populate-webroot step. 22:16:03 INFO - Running pre-action listener: _resource_record_pre_action 22:16:03 INFO - Running main action method: populate_webroot 22:16:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:16:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:16:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:16:03 INFO - '--tppagecycles', 22:16:03 INFO - '7'], 22:16:03 INFO - 'tests': ['tsvgm']}}, 22:16:03 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:16:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:16:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:16:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:16:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:16:03 INFO - 'talos_options': ['--disable-e10s'], 22:16:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:16:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:16:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:16:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:16:03 INFO - 'talos_options': ['--disable-e10s'], 22:16:03 INFO - 'tests': ['damp', 'tps']}, 22:16:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:16:03 INFO - 'tests': ['damp', 'tps']}, 22:16:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:16:03 INFO - 'tests': ['dromaeo_dom']}, 22:16:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:16:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:16:03 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:16:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:16:03 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 22:16:03 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 22:16:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:16:03 INFO - 'tests': ['a11yr', 22:16:03 INFO - 'ts_paint', 22:16:03 INFO - 'tpaint', 22:16:03 INFO - 'sessionrestore', 22:16:03 INFO - 'sessionrestore_no_auto_restore', 22:16:03 INFO - 'tabpaint']}, 22:16:03 INFO - 'other-e10s': {'tests': ['a11yr', 22:16:03 INFO - 'ts_paint', 22:16:03 INFO - 'tpaint', 22:16:03 INFO - 'sessionrestore', 22:16:03 INFO - 'sessionrestore_no_auto_restore', 22:16:03 INFO - 'tabpaint']}, 22:16:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:16:03 INFO - 'ts_paint', 22:16:03 INFO - 'tpaint', 22:16:03 INFO - 'sessionrestore', 22:16:03 INFO - 'sessionrestore_no_auto_restore', 22:16:03 INFO - 'tabpaint']}, 22:16:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:16:03 INFO - 'ts_paint', 22:16:03 INFO - 'tpaint', 22:16:03 INFO - 'sessionrestore', 22:16:03 INFO - 'sessionrestore_no_auto_restore', 22:16:03 INFO - 'tabpaint']}, 22:16:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:16:03 INFO - 'tests': ['a11yr', 22:16:03 INFO - 'ts_paint', 22:16:03 INFO - 'tpaint', 22:16:03 INFO - 'sessionrestore', 22:16:03 INFO - 'sessionrestore_no_auto_restore', 22:16:03 INFO - 'tabpaint']}, 22:16:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:16:03 INFO - 'tests': ['a11yr', 22:16:03 INFO - 'ts_paint', 22:16:03 INFO - 'tpaint', 22:16:03 INFO - 'sessionrestore', 22:16:03 INFO - 'sessionrestore_no_auto_restore', 22:16:03 INFO - 'tabpaint']}, 22:16:03 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:16:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:16:03 INFO - 'bloom_basic_ref']}, 22:16:03 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 22:16:03 INFO - 'talos_options': ['--mitmproxy', 22:16:03 INFO - 'mitmproxy-recording-1.mp', 22:16:03 INFO - '--firstNonBlankPaint'], 22:16:03 INFO - 'tests': ['Quantum_1']}, 22:16:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:16:03 INFO - 'tests': ['tsvgx', 22:16:03 INFO - 'tsvgr_opacity', 22:16:03 INFO - 'tart', 22:16:03 INFO - 'tscrollx', 22:16:03 INFO - 'cart', 22:16:03 INFO - 'tsvg_static']}, 22:16:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:16:03 INFO - 'tsvgr_opacity', 22:16:03 INFO - 'tart', 22:16:03 INFO - 'tscrollx', 22:16:03 INFO - 'cart', 22:16:03 INFO - 'tsvg_static']}, 22:16:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:16:03 INFO - 'talos_options': ['--disable-e10s'], 22:16:03 INFO - 'tests': ['tp5o']}, 22:16:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:16:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:16:03 INFO - 'talos_options': ['--disable-e10s', 22:16:03 INFO - '--xperf_path', 22:16:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:16:03 INFO - 'tests': ['tp5n']}, 22:16:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:16:03 INFO - 'talos_options': ['--xperf_path', 22:16:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:16:03 INFO - 'tests': ['tp5n']}}, 22:16:03 INFO - 'talos.zip': {'path': '', 22:16:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:16:03 INFO - Running post-action listener: _resource_record_post_action 22:16:03 INFO - [mozharness: 2017-06-01 05:16:03.125812Z] Finished populate-webroot step (success) 22:16:03 INFO - [mozharness: 2017-06-01 05:16:03.125907Z] Running create-virtualenv step. 22:16:03 INFO - Running pre-action listener: _resource_record_pre_action 22:16:03 INFO - Running main action method: create_virtualenv 22:16:03 INFO - Creating virtualenv /builds/slave/test/build/venv 22:16:03 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 22:16:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:16:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:16:03 INFO - Using real prefix '/usr' 22:16:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:16:03 INFO - Installing distribute.............................................................................................................................................................................................done. 22:16:05 INFO - Installing pip.................done. 22:16:05 INFO - Return code: 0 22:16:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:16:05 INFO - Reading from file tmpfile_stdout 22:16:05 INFO - Output received: 22:16:05 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:16:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e43b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1562dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', '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 22:16:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:16:05 INFO - Using env: {'DISPLAY': ':0', 22:16:05 INFO - 'HOME': '/home/cltbld', 22:16:05 INFO - 'LANG': 'en_US.UTF-8', 22:16:05 INFO - 'LANGUAGE': 'en_US:en', 22:16:05 INFO - 'LOGNAME': 'cltbld', 22:16:05 INFO - 'MAIL': '/var/mail/cltbld', 22:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:05 INFO - 'MOZ_NO_REMOTE': '1', 22:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:05 INFO - 'NO_EM_RESTART': '1', 22:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:05 INFO - 'PWD': '/builds/slave/test', 22:16:05 INFO - 'SHELL': '/bin/bash', 22:16:05 INFO - 'SHLVL': '1', 22:16:05 INFO - 'TERM': 'linux', 22:16:05 INFO - 'TMOUT': '86400', 22:16:05 INFO - 'USER': 'cltbld', 22:16:05 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:05 INFO - '_': '/tools/buildbot/bin/python'} 22:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:06 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 22:16:06 INFO - Cleaning up... 22:16:06 INFO - Return code: 0 22:16:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:16:06 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')]} 22:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:06 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')]} 22:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:06 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 0x11e43b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1562dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', '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 22:16:06 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 22:16:06 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 22:16:06 INFO - Using env: {'DISPLAY': ':0', 22:16:06 INFO - 'HOME': '/home/cltbld', 22:16:06 INFO - 'LANG': 'en_US.UTF-8', 22:16:06 INFO - 'LANGUAGE': 'en_US:en', 22:16:06 INFO - 'LOGNAME': 'cltbld', 22:16:06 INFO - 'MAIL': '/var/mail/cltbld', 22:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:06 INFO - 'MOZ_NO_REMOTE': '1', 22:16:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:06 INFO - 'NO_EM_RESTART': '1', 22:16:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:06 INFO - 'PWD': '/builds/slave/test', 22:16:06 INFO - 'SHELL': '/bin/bash', 22:16:06 INFO - 'SHLVL': '1', 22:16:06 INFO - 'TERM': 'linux', 22:16:06 INFO - 'TMOUT': '86400', 22:16:06 INFO - 'USER': 'cltbld', 22:16:06 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:06 INFO - '_': '/tools/buildbot/bin/python'} 22:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:06 INFO - Downloading/unpacking psutil>=3.1.1 22:16:06 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 22:16:06 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 22:16:06 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 22:16:06 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 22:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:16:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:16:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:16:07 INFO - Installing collected packages: psutil 22:16:07 INFO - Running setup.py install for psutil 22:16:07 INFO - building 'psutil._psutil_linux' extension 22:16:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:16:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:16:07 INFO - building 'psutil._psutil_posix' extension 22:16:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:16:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:16:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:16:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:16:07 INFO - Successfully installed psutil 22:16:07 INFO - Cleaning up... 22:16:08 INFO - Return code: 0 22:16:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:08 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 0x11e43b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1562dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', '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 22:16:08 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 22:16:08 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 22:16:08 INFO - Using env: {'DISPLAY': ':0', 22:16:08 INFO - 'HOME': '/home/cltbld', 22:16:08 INFO - 'LANG': 'en_US.UTF-8', 22:16:08 INFO - 'LANGUAGE': 'en_US:en', 22:16:08 INFO - 'LOGNAME': 'cltbld', 22:16:08 INFO - 'MAIL': '/var/mail/cltbld', 22:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:08 INFO - 'MOZ_NO_REMOTE': '1', 22:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:08 INFO - 'NO_EM_RESTART': '1', 22:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:08 INFO - 'PWD': '/builds/slave/test', 22:16:08 INFO - 'SHELL': '/bin/bash', 22:16:08 INFO - 'SHLVL': '1', 22:16:08 INFO - 'TERM': 'linux', 22:16:08 INFO - 'TMOUT': '86400', 22:16:08 INFO - 'USER': 'cltbld', 22:16:08 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:08 INFO - '_': '/tools/buildbot/bin/python'} 22:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:16:09 INFO - Installing collected packages: mozsystemmonitor 22:16:09 INFO - Running setup.py install for mozsystemmonitor 22:16:09 INFO - Successfully installed mozsystemmonitor 22:16:09 INFO - Cleaning up... 22:16:09 INFO - Return code: 0 22:16:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16: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')]} 22:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e43b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1562dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', '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 22:16:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:16:09 INFO - Using env: {'DISPLAY': ':0', 22:16:09 INFO - 'HOME': '/home/cltbld', 22:16:09 INFO - 'LANG': 'en_US.UTF-8', 22:16:09 INFO - 'LANGUAGE': 'en_US:en', 22:16:09 INFO - 'LOGNAME': 'cltbld', 22:16:09 INFO - 'MAIL': '/var/mail/cltbld', 22:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:09 INFO - 'MOZ_NO_REMOTE': '1', 22:16:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:09 INFO - 'NO_EM_RESTART': '1', 22:16:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:09 INFO - 'PWD': '/builds/slave/test', 22:16:09 INFO - 'SHELL': '/bin/bash', 22:16:09 INFO - 'SHLVL': '1', 22:16:09 INFO - 'TERM': 'linux', 22:16:09 INFO - 'TMOUT': '86400', 22:16:09 INFO - 'USER': 'cltbld', 22:16:09 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:09 INFO - '_': '/tools/buildbot/bin/python'} 22:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:09 INFO - Downloading/unpacking jsonschema==2.5.1 22:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:16:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:16: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 22:16: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 22:16: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 22:16: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 22:16:10 INFO - Downloading functools32-3.2.3-2.tar.gz 22:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:16:10 INFO - warning: no files found matching '*.txt' 22:16:10 INFO - no previously-included directories found matching 'build' 22:16:10 INFO - no previously-included directories found matching 'dist' 22:16:10 INFO - no previously-included directories found matching '.git*' 22:16:10 INFO - Installing collected packages: jsonschema, functools32 22:16:11 INFO - Running setup.py install for functools32 22:16:11 INFO - warning: no files found matching '*.txt' 22:16:11 INFO - no previously-included directories found matching 'build' 22:16:11 INFO - no previously-included directories found matching 'dist' 22:16:11 INFO - no previously-included directories found matching '.git*' 22:16:11 INFO - Successfully installed jsonschema functools32 22:16:11 INFO - Cleaning up... 22:16:11 INFO - Return code: 0 22:16:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e43b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1562dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', '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 22:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:16:11 INFO - Using env: {'DISPLAY': ':0', 22:16:11 INFO - 'HOME': '/home/cltbld', 22:16:11 INFO - 'LANG': 'en_US.UTF-8', 22:16:11 INFO - 'LANGUAGE': 'en_US:en', 22:16:11 INFO - 'LOGNAME': 'cltbld', 22:16:11 INFO - 'MAIL': '/var/mail/cltbld', 22:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:11 INFO - 'MOZ_NO_REMOTE': '1', 22:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:11 INFO - 'NO_EM_RESTART': '1', 22:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:11 INFO - 'PWD': '/builds/slave/test', 22:16:11 INFO - 'SHELL': '/bin/bash', 22:16:11 INFO - 'SHLVL': '1', 22:16:11 INFO - 'TERM': 'linux', 22:16:11 INFO - 'TMOUT': '86400', 22:16:11 INFO - 'USER': 'cltbld', 22:16:11 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:11 INFO - '_': '/tools/buildbot/bin/python'} 22:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:16:11 INFO - Cleaning up... 22:16:11 INFO - Return code: 0 22:16:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e43b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1562dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', '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 22:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:16:11 INFO - Using env: {'DISPLAY': ':0', 22:16:11 INFO - 'HOME': '/home/cltbld', 22:16:11 INFO - 'LANG': 'en_US.UTF-8', 22:16:11 INFO - 'LANGUAGE': 'en_US:en', 22:16:11 INFO - 'LOGNAME': 'cltbld', 22:16:11 INFO - 'MAIL': '/var/mail/cltbld', 22:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:11 INFO - 'MOZ_NO_REMOTE': '1', 22:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:11 INFO - 'NO_EM_RESTART': '1', 22:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:11 INFO - 'PWD': '/builds/slave/test', 22:16:11 INFO - 'SHELL': '/bin/bash', 22:16:11 INFO - 'SHLVL': '1', 22:16:11 INFO - 'TERM': 'linux', 22:16:11 INFO - 'TMOUT': '86400', 22:16:11 INFO - 'USER': 'cltbld', 22:16:11 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:11 INFO - '_': '/tools/buildbot/bin/python'} 22:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:11 INFO - Downloading/unpacking blobuploader==1.2.4 22:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:12 INFO - Downloading blobuploader-1.2.4.tar.gz 22:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:16:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:16: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 22:16: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 22:16: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 22:16: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 22:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:16:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:16: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 22:16: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 22:16: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 22:16: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 22:16:13 INFO - Downloading docopt-0.6.1.tar.gz 22:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:16:13 INFO - Installing collected packages: blobuploader, requests, docopt 22:16:13 INFO - Running setup.py install for blobuploader 22:16:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:16:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:16:13 INFO - Running setup.py install for requests 22:16:13 INFO - Running setup.py install for docopt 22:16:13 INFO - Successfully installed blobuploader requests docopt 22:16:13 INFO - Cleaning up... 22:16:13 INFO - Return code: 0 22:16:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16: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')]} 22:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e43b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1562dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', '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 22:16:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:16:13 INFO - Using env: {'DISPLAY': ':0', 22:16:13 INFO - 'HOME': '/home/cltbld', 22:16:13 INFO - 'LANG': 'en_US.UTF-8', 22:16:13 INFO - 'LANGUAGE': 'en_US:en', 22:16:13 INFO - 'LOGNAME': 'cltbld', 22:16:13 INFO - 'MAIL': '/var/mail/cltbld', 22:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:13 INFO - 'MOZ_NO_REMOTE': '1', 22:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:13 INFO - 'NO_EM_RESTART': '1', 22:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:13 INFO - 'PWD': '/builds/slave/test', 22:16:13 INFO - 'SHELL': '/bin/bash', 22:16:13 INFO - 'SHLVL': '1', 22:16:13 INFO - 'TERM': 'linux', 22:16:13 INFO - 'TMOUT': '86400', 22:16:13 INFO - 'USER': 'cltbld', 22:16:13 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:13 INFO - '_': '/tools/buildbot/bin/python'} 22:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:16:14 INFO - Running setup.py (path:/tmp/pip-OuFLTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:16:14 INFO - Running setup.py (path:/tmp/pip-ZZLjeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:16:14 INFO - Running setup.py (path:/tmp/pip-GrNQAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:16:14 INFO - Running setup.py (path:/tmp/pip-oYTq7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:16:14 INFO - Running setup.py (path:/tmp/pip-W8PyD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:16:14 INFO - Running setup.py (path:/tmp/pip-8G10VO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:16:14 INFO - Running setup.py (path:/tmp/pip-W9hgaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:16:14 INFO - Running setup.py (path:/tmp/pip-85mQtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:16:14 INFO - Running setup.py (path:/tmp/pip-ESQHs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:16:14 INFO - Running setup.py (path:/tmp/pip-72of1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:16:14 INFO - Running setup.py (path:/tmp/pip-AOS4St-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:16:14 INFO - Running setup.py (path:/tmp/pip-Pg8c6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:16:14 INFO - Running setup.py (path:/tmp/pip-eQjVIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:16:15 INFO - Running setup.py (path:/tmp/pip-BNylHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:15 INFO - Running setup.py (path:/tmp/pip-3loFoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:16:15 INFO - Running setup.py (path:/tmp/pip-uiELJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:16:15 INFO - Running setup.py (path:/tmp/pip-ysG9Sa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:16:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:16:15 INFO - Running setup.py install for manifestparser 22:16:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:16:15 INFO - Running setup.py install for mozcrash 22:16:15 INFO - Running setup.py install for mozdebug 22:16:15 INFO - Running setup.py install for mozdevice 22:16:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:16:15 INFO - Running setup.py install for mozfile 22:16:15 INFO - Running setup.py install for mozhttpd 22:16:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:16:15 INFO - Running setup.py install for mozinfo 22:16:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:16:15 INFO - Running setup.py install for mozInstall 22:16:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:16:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:16:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:16:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:16:16 INFO - Running setup.py install for mozleak 22:16:16 INFO - Running setup.py install for mozlog 22:16:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:16:16 INFO - Running setup.py install for moznetwork 22:16:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:16:16 INFO - Running setup.py install for mozprocess 22:16:16 INFO - Running setup.py install for mozprofile 22:16:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:16:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:16:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:16:16 INFO - Running setup.py install for mozrunner 22:16:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:16:16 INFO - Running setup.py install for mozscreenshot 22:16:16 INFO - Running setup.py install for moztest 22:16:16 INFO - Running setup.py install for mozversion 22:16:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:16:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:16:17 INFO - Cleaning up... 22:16:17 INFO - Return code: 0 22:16:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16: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')]} 22:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e43b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1562dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', '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 22:16:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:16:17 INFO - Using env: {'DISPLAY': ':0', 22:16:17 INFO - 'HOME': '/home/cltbld', 22:16:17 INFO - 'LANG': 'en_US.UTF-8', 22:16:17 INFO - 'LANGUAGE': 'en_US:en', 22:16:17 INFO - 'LOGNAME': 'cltbld', 22:16:17 INFO - 'MAIL': '/var/mail/cltbld', 22:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:17 INFO - 'MOZ_NO_REMOTE': '1', 22:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:17 INFO - 'NO_EM_RESTART': '1', 22:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:17 INFO - 'PWD': '/builds/slave/test', 22:16:17 INFO - 'SHELL': '/bin/bash', 22:16:17 INFO - 'SHLVL': '1', 22:16:17 INFO - 'TERM': 'linux', 22:16:17 INFO - 'TMOUT': '86400', 22:16:17 INFO - 'USER': 'cltbld', 22:16:17 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:17 INFO - '_': '/tools/buildbot/bin/python'} 22:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:16:17 INFO - Running setup.py (path:/tmp/pip-8FRobV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:16:17 INFO - Running setup.py (path:/tmp/pip-WtyjVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:16:17 INFO - Running setup.py (path:/tmp/pip-F8gVbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:16:17 INFO - Running setup.py (path:/tmp/pip-dl1Ypf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:16:17 INFO - Running setup.py (path:/tmp/pip-dPX8H3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:16:17 INFO - Running setup.py (path:/tmp/pip-0PRHlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:16:17 INFO - Running setup.py (path:/tmp/pip-vSbTSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 22:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:16:17 INFO - Running setup.py (path:/tmp/pip-1yicEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 22:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:16:17 INFO - Running setup.py (path:/tmp/pip-igFE3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:16:17 INFO - Running setup.py (path:/tmp/pip-f9_MmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 22:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:16:18 INFO - Running setup.py (path:/tmp/pip-sFKo7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:16:18 INFO - Running setup.py (path:/tmp/pip-YFFDMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:16:18 INFO - Running setup.py (path:/tmp/pip-dSMNat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:16:18 INFO - Running setup.py (path:/tmp/pip-lStnoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:18 INFO - Running setup.py (path:/tmp/pip-AQVRuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:16:18 INFO - Running setup.py (path:/tmp/pip-qJh149-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:16:18 INFO - Running setup.py (path:/tmp/pip-LnWIGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:16:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:19 INFO - Downloading blessings-1.6.tar.gz 22:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:16:19 INFO - Installing collected packages: blessings 22:16:19 INFO - Running setup.py install for blessings 22:16:20 INFO - Successfully installed blessings 22:16:20 INFO - Cleaning up... 22:16:20 INFO - Return code: 0 22:16:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:16:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:16:20 INFO - Reading from file tmpfile_stdout 22:16:20 INFO - Current package versions: 22:16:20 INFO - argparse == 1.2.1 22:16:20 INFO - blessings == 1.6 22:16:20 INFO - blobuploader == 1.2.4 22:16:20 INFO - docopt == 0.6.1 22:16:20 INFO - functools32 == 3.2.3-2 22:16:20 INFO - jsonschema == 2.5.1 22:16:20 INFO - manifestparser == 1.1 22:16:20 INFO - mozInstall == 1.12 22:16:20 INFO - mozcrash == 1.0 22:16:20 INFO - mozdebug == 0.1 22:16:20 INFO - mozdevice == 0.51 22:16:20 INFO - mozfile == 1.2 22:16:20 INFO - mozhttpd == 0.7 22:16:20 INFO - mozinfo == 0.9 22:16:20 INFO - mozleak == 0.1 22:16:20 INFO - mozlog == 3.4 22:16:20 INFO - moznetwork == 0.27 22:16:20 INFO - mozprocess == 0.25 22:16:20 INFO - mozprofile == 0.28 22:16:20 INFO - mozrunner == 6.13 22:16:20 INFO - mozscreenshot == 0.1 22:16:20 INFO - mozsystemmonitor == 0.3 22:16:20 INFO - moztest == 0.8 22:16:20 INFO - mozversion == 1.4 22:16:20 INFO - psutil == 3.1.1 22:16:20 INFO - requests == 1.2.3 22:16:20 INFO - wsgiref == 0.1.2 22:16:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e43b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1562dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', '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 22:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:16:20 INFO - Using env: {'DISPLAY': ':0', 22:16:20 INFO - 'HOME': '/home/cltbld', 22:16:20 INFO - 'LANG': 'en_US.UTF-8', 22:16:20 INFO - 'LANGUAGE': 'en_US:en', 22:16:20 INFO - 'LOGNAME': 'cltbld', 22:16:20 INFO - 'MAIL': '/var/mail/cltbld', 22:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:20 INFO - 'MOZ_NO_REMOTE': '1', 22:16:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:20 INFO - 'NO_EM_RESTART': '1', 22:16:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:20 INFO - 'PWD': '/builds/slave/test', 22:16:20 INFO - 'SHELL': '/bin/bash', 22:16:20 INFO - 'SHLVL': '1', 22:16:20 INFO - 'TERM': 'linux', 22:16:20 INFO - 'TMOUT': '86400', 22:16:20 INFO - 'USER': 'cltbld', 22:16:20 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:20 INFO - '_': '/tools/buildbot/bin/python'} 22:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:16:20 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 22:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:21 INFO - Downloading marionette_driver-1.4.0.tar.gz 22:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 22:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:16:21 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)) 22:16:21 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)) 22:16:21 INFO - Installing collected packages: marionette-driver 22:16:21 INFO - Running setup.py install for marionette-driver 22:16:21 INFO - Successfully installed marionette-driver 22:16:21 INFO - Cleaning up... 22:16:21 INFO - Return code: 0 22:16:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16: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')]} 22:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:21 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 0x11e43b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1562dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', '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 22:16:21 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 22:16:21 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 22:16:21 INFO - Using env: {'DISPLAY': ':0', 22:16:21 INFO - 'HOME': '/home/cltbld', 22:16:21 INFO - 'LANG': 'en_US.UTF-8', 22:16:21 INFO - 'LANGUAGE': 'en_US:en', 22:16:21 INFO - 'LOGNAME': 'cltbld', 22:16:21 INFO - 'MAIL': '/var/mail/cltbld', 22:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:21 INFO - 'MOZ_NO_REMOTE': '1', 22:16:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:21 INFO - 'NO_EM_RESTART': '1', 22:16:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:21 INFO - 'PWD': '/builds/slave/test', 22:16:21 INFO - 'SHELL': '/bin/bash', 22:16:21 INFO - 'SHLVL': '1', 22:16:21 INFO - 'TERM': 'linux', 22:16:21 INFO - 'TMOUT': '86400', 22:16:21 INFO - 'USER': 'cltbld', 22:16:21 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:21 INFO - '_': '/tools/buildbot/bin/python'} 22:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:16:22 INFO - Cleaning up... 22:16:22 INFO - Return code: 0 22:16:22 INFO - Running post-action listener: _resource_record_post_action 22:16:22 INFO - Running post-action listener: _start_resource_monitoring 22:16:22 INFO - Starting resource monitoring. 22:16:22 INFO - [mozharness: 2017-06-01 05:16:22.138945Z] Finished create-virtualenv step (success) 22:16:22 INFO - [mozharness: 2017-06-01 05:16:22.139457Z] Running install step. 22:16:22 INFO - Running pre-action listener: _resource_record_pre_action 22:16:22 INFO - Running main action method: install 22:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:16:22 INFO - Reading from file tmpfile_stdout 22:16:22 INFO - Detecting whether we're running mozinstall >=1.0... 22:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:16:22 INFO - Reading from file tmpfile_stdout 22:16:22 INFO - Output received: 22:16:22 INFO - Usage: mozinstall [options] installer 22:16:22 INFO - Options: 22:16:22 INFO - -h, --help show this help message and exit 22:16:22 INFO - -d DEST, --destination=DEST 22:16:22 INFO - Directory to install application into. [default: 22:16:22 INFO - "/builds/slave/test"] 22:16:22 INFO - --app=APP Application being installed. [default: firefox] 22:16:22 INFO - mkdir: /builds/slave/test/build/application 22:16:22 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'] 22:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 22:16:37 INFO - Reading from file tmpfile_stdout 22:16:37 INFO - Output received: 22:16:37 INFO - /builds/slave/test/build/application/firefox/firefox 22:16:37 INFO - Running post-action listener: _resource_record_post_action 22:16:37 INFO - [mozharness: 2017-06-01 05:16:37.990719Z] Finished install step (success) 22:16:37 INFO - [mozharness: 2017-06-01 05:16:37.990998Z] Running setup-mitmproxy step. 22:16:37 INFO - Running pre-action listener: _resource_record_pre_action 22:16:37 INFO - Running main action method: setup_mitmproxy 22:16:37 INFO - Skipping: mitmproxy is not required 22:16:37 INFO - Running post-action listener: _resource_record_post_action 22:16:37 INFO - [mozharness: 2017-06-01 05:16:37.992204Z] Finished setup-mitmproxy step (success) 22:16:37 INFO - [mozharness: 2017-06-01 05:16:37.992426Z] Running run-tests step. 22:16:37 INFO - Running pre-action listener: _resource_record_pre_action 22:16:37 INFO - Running main action method: run_tests 22:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:16:37 INFO - Python 2.7.3 22:16:37 INFO - Return code: 0 22:16:37 INFO - grabbing minidump binary from tooltool 22:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:38 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 0x155f840>, '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 0x1560740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1560bd0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:16:38 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 22:16:38 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 22:16:38 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 22:16:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:16:38 INFO - Return code: 0 22:16:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:16:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:16:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:16:38 INFO - ENV: RUST_BACKTRACE is now 1 22:16:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:16:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:16:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 22:16:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 22:16:38 INFO - Using env: {'DISPLAY': ':0', 22:16:38 INFO - 'HOME': '/home/cltbld', 22:16:38 INFO - 'LANG': 'en_US.UTF-8', 22:16:38 INFO - 'LANGUAGE': 'en_US:en', 22:16:38 INFO - 'LOGNAME': 'cltbld', 22:16:38 INFO - 'MAIL': '/var/mail/cltbld', 22:16:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:16:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:38 INFO - 'MOZ_NO_REMOTE': '1', 22:16:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:16:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:38 INFO - 'NO_EM_RESTART': '1', 22:16:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:38 INFO - 'PWD': '/builds/slave/test', 22:16:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:16:38 INFO - 'RUST_BACKTRACE': '1', 22:16:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:16:38 INFO - 'SHELL': '/bin/bash', 22:16:38 INFO - 'SHLVL': '1', 22:16:38 INFO - 'TERM': 'linux', 22:16:38 INFO - 'TMOUT': '86400', 22:16:38 INFO - 'USER': 'cltbld', 22:16:38 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154', 22:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:38 INFO - '_': '/tools/buildbot/bin/python'} 22:16:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 22:16:38 INFO - mozversion application_buildid: 20170601042623 22:16:38 INFO - mozversion application_changeset: c81fbc7bbc63ccde05ba7bc132b9cbd57f606da0 22:16:38 INFO - mozversion application_display_name: Nightly 22:16:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:16:38 INFO - mozversion application_name: Firefox 22:16:38 INFO - mozversion application_remotingname: firefox 22:16:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:16:38 INFO - mozversion application_vendor: Mozilla 22:16:38 INFO - mozversion application_version: 55.0a1 22:16:38 INFO - mozversion platform_buildid: 20170601042623 22:16:38 INFO - mozversion platform_changeset: c81fbc7bbc63ccde05ba7bc132b9cbd57f606da0 22:16:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:16:38 INFO - mozversion platform_version: 55.0a1 22:16:38 INFO - using testdate: 1496294198 22:16:38 INFO - actual date: 1496294198 22:16:38 INFO - starting webserver on 'localhost:39798' 22:16:38 INFO - SUITE-START | Running 2 tests 22:16:38 INFO - TEST-START | dromaeo_css 22:16:38 INFO - operating with platform_type : linux_ 22:16:38 INFO - Initialising browser for dromaeo_css test... 22:16:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39798/getInfo.html -profile /tmp/tmpbt5tJJ/profile 22:16:38 INFO - TEST-INFO | started process 4274 (/builds/slave/test/build/application/firefox/firefox http://localhost:39798/getInfo.html) 22:17:12 INFO - TEST-INFO | 4274: killed by SIGTERM 22:17:13 INFO - Browser initialized. 22:17:13 INFO - Running cycle 1/1 for dromaeo_css test... 22:17:13 INFO - TEST-INFO | started process 4481 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbt5tJJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:17:24 INFO - PID 4481 | RSS: Main: 184766464 22:17:24 INFO - PID 4481 | 22:20:30 INFO - PID 4481 | Cycle 1(1): loaded http://localhost:39798/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39798/tests/dromaeo/cssquery-ext.html) 22:20:31 INFO - PID 4481 | RSS: Main: 178466816 22:20:31 INFO - PID 4481 | 22:23:33 INFO - PID 4481 | Cycle 1(1): loaded http://localhost:39798/tests/dromaeo/cssquery-ext.html (next: http://localhost:39798/tests/dromaeo/cssquery-jquery.html) 22:23:33 INFO - PID 4481 | RSS: Main: 184684544 22:23:33 INFO - PID 4481 | 22:26:35 INFO - PID 4481 | Cycle 1(1): loaded http://localhost:39798/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39798/tests/dromaeo/cssquery-mootools.html) 22:26:36 INFO - PID 4481 | RSS: Main: 181592064 22:26:36 INFO - PID 4481 | 22:29:37 INFO - PID 4481 | Cycle 1(1): loaded http://localhost:39798/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39798/tests/dromaeo/cssquery-prototype.html) 22:29:38 INFO - PID 4481 | RSS: Main: 185761792 22:29:38 INFO - PID 4481 | 22:32:41 INFO - PID 4481 | Cycle 1(1): loaded http://localhost:39798/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39798/tests/dromaeo/cssquery-yui.html) 22:32:42 INFO - PID 4481 | RSS: Main: 184082432 22:32:42 INFO - PID 4481 | 22:35:43 INFO - PID 4481 | Cycle 1(1): loaded http://localhost:39798/tests/dromaeo/cssquery-yui.html (next: http://localhost:39798/tests/dromaeo/cssquery-dojo.html) 22:35:44 INFO - PID 4481 | RSS: Main: 181739520 22:35:44 INFO - PID 4481 | 22:35:44 INFO - PID 4481 | __start_tp_report 22:35:44 INFO - PID 4481 | _x_x_mozilla_page_load 22:35:44 INFO - PID 4481 | _x_x_mozilla_page_load_details 22:35:44 INFO - PID 4481 | |i|pagename|runs| 22:35:44 INFO - PID 4481 | |0;dojo.html;2725.972740272597;2747.491714032903;2755.7382048705367;2780.6663200415946;2781.3046738315434;5041.974790126044;5246.816361427346;5271.499207575283;5293.682379057259;5296.629235953485;5186.299849520314;5216.686998780076;5260.316158899338;5281.524662780349;5292.179712144623;4464.628437828691;4635.46692130406;4677.462091859437;4707.6233901287815;4710.246360582308;4750.2874568814605;4769.236922092466;4774.474807771142;4793.976030119845;4802.783874725628;4620.5841474267145;4636.86089417318;4693.530646935279;4718.882027949295;4719.126961512097;4702.694324868872;4703.129920964632;4704.882377940545;4724;4750.596199323028;4640.5127461616385;4660;4661.836835710767;4665.346851440801;4679.461861885893;5122.974385128088;5167.9224811627855;5201.713905735183;5226.268322434862;5228.503292187236;5678.460546248141;5681.943180568224;5716.399778023331;5742.253507044099;5760.567957403212;5712.285964254468;5714.685692286923;5717.456841600041;5723.54211663066;5736.483716465496;5230.843074707765;5300.44345343741;5300.946990530046;5316.388615309212;5373.543248823894;5736.913946290809;5750.6262092963825;5849.385814489502;5869.295684517885;5900.881982360414;5471.753771080311;5542.667439953616;5551.111822108443;5613.326400831927;5634.5210657093785;5503.477169668875;5663.292088488939;5663.858403539862;5678.488935995698;5693.4875861172695;5633.380328163898;5639.8590035249445;5641;5658.773649054074;5664.631798933056;5480.835574932758;5530.170474428868;5547.833564993057;5634.915476267859;5661.575381846378;4703.623710103184;4704.129735998851;4704.529547045337;4732.337472753818;4744.596709279681;4003.659688926474;4007;4012.277789997828;4059.6143366380147;4077.796110194538;4692.0850434165695;4745.217039188564;4755.334253204554;4762.309465127606;4790.808367665324;5750.6262092963825;5756.827295181151;5758.798442054508;5766.625169363978;5771.971140144273;4799.712017278975;4810.230363141881;4819.277108433763;4821.276808478686;4842.297866809364;4849.830255941025;4937.1113199624415;4956;5043.87390315245;5079.1365467870555;5271;5299.523042926001;5338;5343.519083282367;5352.090144675492;5660.971695141498;5685.886282274414;5701.800436984686;5708.743106560131;5714.428557144252;5185.662931909414;5264.10510213257;5286.524212820865;5297.682139071668;5309.548688361532;5512.283403157564;5569.10894256917;5617.831465056055;5634.690092044977;5645.830625081254;5755.3381361144;5762.711864406847;5779.7110144493445;5783.421657834183;5832.737526811219;5341.679499230058;5419.918701219406;5453.809116680897;5546.861328466659;5574.9442505574425;5481.643693159932;5527.115661494142;5579.023670857665;5581.358143813513;5601.103823388238;5337;5408.7566059526625;5423.078076726887;5469.972650136724;5482.259894914295;5494.1758736189895;5519.420460851553;5556.638818476786;5588.636738611977;5618.831435056954;5380.757865896122;5389.622726409114;5393.1370980642905;5396.1905714143195;5405.756675964629 22:35:44 INFO - PID 4481 | |1;ext.html;721.801504586239;756.6595032235493;774.1175060431108;1078.8813230544645;1093.3494570730413;32251;33308.50037249443;33316.66683333166;33335.83332083339;33355.16612084699;35296.2940741185;35620.643793562056;35655;35686.2862742745;35782.82108589453;6533.281339052701;6669.566478178927;6806.3533964273565;6886.621235832027;6937.7571784987285;6690;6735.5621884577595;6765.797026089225;6768.3231676832165;6792.524523283372;6730.0577919091365;6783.932160678404;6786.55887367322;6786.796396108125;6804.18349798025;6731.360520750521;6752;6771.966140169267;6782.220044694849;6784.118064651613;6676.298988606175;6696;6726.058351830748;6743.460523158135;6766.424853887376;32142.357152857046;32158;32189.51715724266;32238.194045148823;32284.031479055666;2969.8069625474277;2989.7757668174754;2990.820550766939;2993.476141675198;3002.9549556756665;24437.266881993568;24509;24611.50776984468;24668.753312466826;24673.753262467326;2968.391479746668;2975.851207439641;2988.9402211955858;2990.5794747494983;3005.6693763685976;123803.38098309541;126095.36952315271;137935.999999999;141833;142479;39238.607613923494;39491.210175796325;39591;39674.60325396767;39696.801515992236;40340;40403.39394909079;40558;40564.59435405667;40569.797151014645;12762.9361853192;12922.806157907638;12924.870751292367;12965.870341296466;12970.675733106747;10466.267361284788;10500.947495262628;10507.684769456928;10543.103836173857;10563.20775941792;12380.257184568954;12401;12427.93786031064;12549.811752823714;12775.105742598114;5195.376554813447;5281.313429254173;5306.124489459196;5324.866878327995;5339.839804805862;839.5927974932154;877.8288233794476;880.5201165364891;889.4040992535017;907.7821322882461;19781;20395.592088158155;21126.683099753514;21263.149474020865;21303.36089917305;948.5778828421425;949.4255975135083;981.4945303168873;983.2674657380297;991.7124034030195;10397.532111055021;11093.83359249612;11151.72120696989;11197.888021119685;11342;179969.100154501;180794;182575;182602;183353;41157.58842411597;41255.58744412517;41344.1731165375;41597.79201103975;41726.37410438846;1191.142377488207;1218.585680868509;1223.0643557678384;1225.4179264849126;1227.4783217132683;19276.61446771085;19589.80410195937;20194.29319973794;20707.171713131305;21199.68200476963;29561.40877182401;29646.851765741034;29678.258043549085;29684.55473167861;29779;158095;159296.20351898167;159330;159462;160113;28213.858930705213;28312.433751324446;28475.85762071259;29415.411691766472;29516.409671806872;35623.64376356307;36182.81908590545;36232.63767362398;36245;36258.637413625525;26216.60675089837;27399.589006165323;27453.17640470789;27590.586141207496;28146.577801332587;26879.86560067187;27179.320516987234;27412.725872741816;27818;28431;30611.2347191322;30657.233569160948;31051.534226987063;31067.378652427276;31108.377832443675;26398.20805375842;26611.33471663224;27056.864715676296;27186.864065679543;27978.440431190855;21544.569108618052;22132.446688832908;22375.776242238022;22545.774542254367;22724 22:35:44 INFO - PID 4481 | |2;jquery.html;408.3935355996345;416.33594416905026;429.9806508707108;433.73325404876;438.59430027224806;17713.380031698893;18315.358962436316;18390.81609183908;18393.264269429223;20278.797212027876;19597.51006224845;19930.70103948442;20052;20131.396058118244;20139.899300503515;5604.159376093588;5734.168545560891;5737.225474560943;5741.971290143543;5743.138529220609;5264.842054738364;5393.6763794172475;5428.9185662215095;5676.716164191795;5716.828495145152;5386.78452861887;5400.972995135019;5414.648047876895;5414.7834086636485;5431.34823821142;5373.865653358658;5403.162509810985;5405.648632838853;5412.539934105605;5422.267993820843;4902.877428064291;4903.558679718807;4920.7539623019065;4926.0394223126505;4934.950650493485;23429.882850585807;23655.88172059146;24020.63969040466;24030.038798448215;24174.274771756875;1635.6074542109927;1673.1383337581156;1673.8493535581856;1676.1702957036346;1678.6474840283556;15382.230888455533;15882.841171588252;15916.681666366723;15967.041977481389;15982.360705571764;2016.1632922337212;2078.3349328214977;2104.873707577535;2118.343313572782;2127.053461209762;103251;105044;105888.4705576475;105897.47051264695;106038.46980765047;10478.63324783629;11056.502457389437;11158.386288754196;11377.943110284396;11559.595414160633;15846.683066338608;15999.520014399586;16010.359585616445;16019.43931962399;16022.919885400499;7856.685732570748;7917.208279172152;7990.4406691532195;8050.436469447082;8083.151269116775;7296.270372962767;7374.078240219973;7492.887606685904;7513.549187048795;7818.648160832776;7355.880706804207;7472.962635186898;7497.887531687029;7532.209118042636;7534.095908490906;2053.5687505623687;2063.9484012899497;2108.125128071833;2115.6403411420097;2176.614122543077;783.8275579372529;835.1314632781961;838.8070743728977;857.1985193843776;862.3316929379781;21339.35981920545;23381;23408.765912340998;23575.41061473477;23581.88209058944;850.995745021271;852.8251708399763;852.9189726975927;856.4005196362571;868.9695860644847;9702.029797020383;9736.561854716554;9808.117269445784;10252;11868.525258989717;376968;377698;381460;382719;386480;91217.54391228002;92353;93199.53400232956;94111.0588894116;94276;1056.434807378049;1085.7882712871071;1092.3718861654422;1102.536934487517;1105.7069876482738;18595.349162779778;18636.254549817855;18807.623847523246;19145.712814308074;20402.693959590913;45975.61642881669;45989.540104599866;46415.30377044279;46523.53476465192;46587.30119048149;53265;53364;53435.46564534305;53598.46401535935;53676.46323536715;9614.567344469655;9825.754356141153;9934.602615895283;9956.303058785816;9985.350952188084;15859.207039648201;15911.522654320388;15934.60980852835;15956.36719941243;16067.839321606634;13302.401391937192;13804.792928106288;13821.792673110112;13852.584422467342;14011.859881401055;12338.198017128858;12851.485940562272;12888.871111288767;13077.41151648159;13167.53913613019;13450.12574182675;13497.122687025312;13664.93167534156;14295.28523573838;14414.351354188877;9563.904360956301;9577.760555985877;9625;9647.228221742384;9671.70984870455;13776.311184440938;13885.791713124108;13896.930515347358;13899;13911 22:35:44 INFO - PID 4481 | |3;mootools.html;1279.1301914698008;1332.1540821578299;1336.2383441438385;1348.7639663058962;1352.3981828086498;10145.340552864069;10462.633807816728;10593.523291451884;10626.14990800736;10736;10501.264911456183;10568.048875601195;10655.200859935498;10658.893411065887;10671.359718416881;2138.2409244718133;2184.8798316092607;2185.0604240176717;2190.7371115466167;2199.670049492573;2183.650615901456;2295.8622482651094;2295.9655605165935;2297.6094064009076;2298.0577963035157;2138.337115494202;2145.5172586168064;2158.6546152615583;2163.3293678959494;2166.490874644457;2035.6132334856477;2058.433930669063;2064.1846470644164;2077.594869000538;2080.9895950520227;2075.0869617368307;2129.8935053247274;2138.4119367173994;2140.3792900058966;2140.4862832920144;9638.180754635865;9710.61157553697;9804.01959804021;9805.068518490732;9823.557939892722;5448.645838020556;5646.915296270559;5662.320521537401;5664.915026274609;5667.234923285386;3225.080851957181;3228.580284563015;3292.9176770580925;3359.8992030239133;3372.207531230159;1012.8480727890802;1047.3820445936933;1063.803196408667;1069.5400977579638;1073.0342691577564;14857.554273371816;14969.550913472613;15169;15193;15413.614659633598;4909.582685471703;4950.18321976877;5059.822906198339;5066.569341605931;5099.59203263738;11389.94305028486;11391.088712902947;11425.257358271685;11464.4267786612;11469.827952580552;5373.328333958255;5418.241446197536;5426.457354264542;5437.728113594305;5439.782408703687;4901.166801643729;4948.90102197954;4955.281484184773;4960.379952505937;4965.975170124127;3615.5299811024397;3702.7593206441493;3718.851245950186;3776.244751049801;3777.376732839051;1134.8865113488584;1147.512307269407;1148.7243061665308;1165.9591914283128;1172.6775136837439;696.8188271049563;718.105958082191;730.7369347035063;734.9485536012535;742.7289039500652;5431.402545719968;5503.724813759296;5578.30271216098;5617.747201375948;5636.97181514098;539.7840863654491;543.3371287029818;546.907025805614;547.3322546493272;566.5722379603455;3378.9493157602656;3886.9028274292805;4618.422697162855;4741.691790033637;4751.976240118777;13311.933440332736;13613.863861361458;13626.318684065756;13657.112287701466;13659.248741319323;12146.878531214752;12857.807132893015;13249.337533123306;13475.663108422368;13498.122622029538;6931.930680693128;7155.856882862314;7302.890456643155;7337.59643219628;7350.081239845126;5878.147668587945;6080.543959202989;6110.908336375047;6251.843703907439;6468.126802881551;11721.706957326134;12171.878281217429;12180.756384872077;12395.318257495925;12558.995280377365;12357.764223577571;12541.310227937187;12567.245965242115;12762.234265944073;12770.36148192568;8091.514509129471;8374.701921202224;8415.242628163494;8429.4942303462;8452;9094.499802510925;9149.63401463934;9158.77103072402;9159.175674189355;9204.539773011456;7583.317501424898;7897.565633890191;7904.723334683258;7935.722249721232;8129.18708129206;7475.476716629783;7504.437167212372;7512.361449276873;7703.422243331661;7703.537787732754;6820;6886.655667216719;6946.79159625212;6948.33990770876;6952.617606031515;4714.952850471452;4727.4799772025735;4764.4282686077895;4828.179209534317;4920.21276595743;4840.588549973291;4882.340883980617;4908.631852610997;5034.471380504994;5081.060003899291 22:35:44 INFO - PID 4481 | |4;prototype.html;377.99811000944993;384.72684394080204;386.61338661338664;388.6696308138081;392.36241108199175;5389.469897217324;5419.918701219485;5459.972700136499;5486.451354864516;5554.777808887649;5333.38666053404;5410.540104091147;5417.26866872972;5424.1321388577835;5456.727163641822;4075.6943229257777;4153.210889930919;4183.184279065584;4189.895252618679;4212.578742125778;4209.6421804146685;4212.304969679999;4218.915621687564;4219.008532994744;4222.7753951354225;4168.832726836476;4176.540580536153;4188.371744238359;4188.5968200652815;4203.222403855297;4190.601892820192;4199.45407097077;4207.936880946788;4209.410682504452;4218.641415479688;3729.627037296276;3733.178700685845;3735.7198210134347;3738.102855314732;3746.2507498500136;5279.782491353641;5326.8305358311745;5349.796295833446;5355.758990845421;5362.517373436371;4956.553910148104;4962.478939711351;4965.329680493123;4990.351254336949;4992.550670439678;5697.088465845486;5734.283214598176;5740.7703691852275;5745.942540574624;5756.697321071563;4159.292920203573;4169.207850508394;4172.91654166918;4184.727992680466;4189.413482112506;5710.2576665033275;6065;6074.787382441637;6110.297315808739;6159.599626024294;4180.456540649696;4241.448611680462;4256.106217694257;4281.207976524353;4282.550332215135;4285.957140428618;4301.440812694393;4320.416743739618;4320.675949303814;4321.719088259316;4720.811167553328;4875.634327425457;4896.853094407174;4897.436794768647;4911.803527858917;5767.077267637158;5772.595918285764;5786.42135786418;5789.652620842763;5797.652140871562;1467.9192644404661;1481.8073650425376;1485.1831492678984;1492.417956996772;1497.827749808786;981.6440542697583;1011.5953618552638;1032.3702541449709;1034.632705389591;1040.152275895143;4945.950540494621;4957.652964292537;4976.576990955737;4994.725290109079;5003.174476211457;5953.814709130595;5968.85077873048;5977.252843394575;5985.910211346833;5995.580309378389;4877.585405240523;4921.384826896638;4942;4959.975200123977;4960.975195124001;3095;3150.212446888278;3164.746820254374;3169.112648458435;3172.762042846796;5497.5601951843755;5510.9448905511235;5553.694546799965;5574.470425309589;5599.356074051455;6077.1491991121275;6134.98772702508;6141.201643786369;6148.1699970503405;6170.845728856814;5478.178273258964;5522.6134170608475;5540.2243685884005;5552.888942221133;5555.50000499957;5918.822435326947;5955.67243801595;5965.463108320272;5968.045112782108;6012.278526576839;5828.184054232326;5851.414858514115;5902.498287645598;5916.7041647918295;5928.614640048383;5302.28419163408;5370.301860758076;5430.945690543202;5492.423295554069;5539.446055394428;4238;4248.702590818737;4249.447571815768;4251.234777739913;4277.6791740618955;5876.647401155944;5901.763929442946;5923.49650279731;5970.671613061149;5980.1328807321825;5627.943720562742;5708.771649133988;5806.506446952057;5834.124881267844;5842.561807864512;5815.912761308668;5823.475887170175;5823.70881455934;5844.12338149281;5844.94155058444;5721.542276617777;5727.8854422912145;5730.627509211888;5744.5978781486565;5762.135679648086;5832.154337621106;5836.270466191726;5848.239728835394;5856.707164641836;5883.176355310175;5648.745806438802;5663.631863928831;5692.345380281155;5708.257926469532;5739.8565035874435 22:35:44 INFO - PID 4481 | |5;yui.html;736.1093077376374;751.3951269228272;759.4683721395024;759.9506032107912;770.5222761887629;12501.937180365228;13182.945364370851;14187.361568729406;14789.1126532408;14925.552233433002;14781.260936953136;15400.845991540082;15488.84511154891;15492.5352239433;15530.689386212269;881.5063564403954;889.1375365895071;891.0910870911666;892.1346294094718;892.8258989497051;836.6109758962082;895.9964839379903;899.6806133822464;902.3277658144691;903.6882275614902;946.6544711180404;948.0330063335393;948.127722495306;948.6821914658598;949.924006079512;936.7798567336671;937.1706040154444;939.3518472254123;939.5161491831713;946.5835032585675;685.5817951049855;689.1557841643996;689.8068540808582;692.0622556436057;695.6347917343385;14925.25373731309;15229.695406091816;15334.23328833554;15426;15429.151396673178;15858;16304.184790760533;16311;16335.183240838029;16427.260773265232;12490;12526;12982.805257921138;12992.220466772023;13030.934845325808;2968.7031296870355;3000.8949686761075;3008.127642983532;3010.3979204159036;3011.3073992981517;80603.5969820153;82165;82272.17727822706;82635;82962.58518707368;12754;12775.680607984688;12816.551420700418;13002;13056.543020994219;22717.318480445614;22901.541969160524;23005.424864378525;23096;23122.537549249257;6245.219347581552;6449.290578036413;6460.806175814733;6502.317256688074;6510.25132109804;3010.443068032421;3083.7378822799865;3087.768417368706;3089.5056790913345;3093.474109401407;3712.7029837612927;4150.979245103756;4418.55814418562;4436.290193569013;4445.711028783118;774.6707649249047;797.2824457987857;802.1016461563087;815.2255357410484;816.7794695432319;578.9681567513827;581.2501872584403;596.3827438601027;598.2312728144313;605.9333473317931;8305;8429.452085614415;8588.441751286271;8621.78445538854;9072.501012444247;576.2623841482908;578.4764787866987;582.621296157501;592.8754961458056;601.5458328961606;3917.216556688674;4050.3924411338535;4069.3895915612893;4204.642605378516;4220.366944958281;84512;86716;86914;87079;88339.11660883437;73802.89295660569;75604;77232;77324.61337693276;77978;66706.33293667196;67094;67258;67461.6626916872;67706;8046.959765201371;8052.677892884219;8055.597220138852;8070.636821342936;8087.47431416956;26570.86714566415;27198.456030879668;27238.591421129095;27795;28528;49340.25989610087;52472.737636311576;53072;53161.734191330346;53873;13885.652858678613;13896.027278090436;14207;14276;14304.856951430353;15325.616859578155;15482.690346193238;15604;15646.608834779221;15982.520524384287;14001.929990349983;14605.561833145022;14857;16131.596710082342;16372.83627163713;13954.930225348808;15328.923355383151;15340.079595224322;16012.199390030219;16313.184340783151;14945.701085978002;15594.454194103151;15690.843091568939;15718.292676829342;15795.21023948775;10164.18686505063;10369.170466362522;10474.633387831389;10523.894761052597;11228.55085796559;11305.43472826339;12162.148649594443;12171.695707607381;12277.140600157903;12398.752024959269 22:35:44 INFO - PID 4481 | __end_tp_report 22:35:44 INFO - PID 4481 | __start_cc_report 22:35:44 INFO - PID 4481 | _x_x_mozilla_cycle_collect,378 22:35:44 INFO - PID 4481 | __end_cc_report 22:35:44 INFO - PID 4481 | __startTimestamp1496295344184__endTimestamp 22:35:44 INFO - PID 4481 | 22:35:44 INFO - PID 4481 | ------- Summary: start ------- 22:35:44 INFO - PID 4481 | Number of tests: 6 22:35:44 INFO - PID 4481 | 22:35:44 INFO - PID 4481 | [#0] dojo.html Cycles:165 Average:5181.57 Median:5338.00 stddev:614.78 (11.5%) stddev-sans-first:585.71 22:35:44 INFO - PID 4481 | Values: 2726.0 2747.5 2755.7 2780.7 2781.3 5042.0 5246.8 5271.5 5293.7 5296.6 5186.3 5216.7 5260.3 5281.5 5292.2 4464.6 4635.5 4677.5 4707.6 4710.2 4750.3 4769.2 4774.5 4794.0 4802.8 4620.6 4636.9 4693.5 4718.9 4719.1 4702.7 4703.1 4704.9 4724.0 4750.6 4640.5 4660.0 4661.8 4665.3 4679.5 5123.0 5167.9 5201.7 5226.3 5228.5 5678.5 5681.9 5716.4 5742.3 5760.6 5712.3 5714.7 5717.5 5723.5 5736.5 5230.8 5300.4 5300.9 5316.4 5373.5 5736.9 5750.6 5849.4 5869.3 5900.9 5471.8 5542.7 5551.1 5613.3 5634.5 5503.5 5663.3 5663.9 5678.5 5693.5 5633.4 5639.9 5641.0 5658.8 5664.6 5480.8 5530.2 5547.8 5634.9 5661.6 4703.6 4704.1 4704.5 4732.3 4744.6 4003.7 4007.0 4012.3 4059.6 4077.8 4692.1 4745.2 4755.3 4762.3 4790.8 5750.6 5756.8 5758.8 5766.6 5772.0 4799.7 4810.2 4819.3 4821.3 4842.3 4849.8 4937.1 4956.0 5043.9 5079.1 5271.0 5299.5 5338.0 5343.5 5352.1 5661.0 5685.9 5701.8 5708.7 5714.4 5185.7 5264.1 5286.5 5297.7 5309.5 5512.3 5569.1 5617.8 5634.7 5645.8 5755.3 5762.7 5779.7 5783.4 5832.7 5341.7 5419.9 5453.8 5546.9 5574.9 5481.6 5527.1 5579.0 5581.4 5601.1 5337.0 5408.8 5423.1 5470.0 5482.3 5494.2 5519.4 5556.6 5588.6 5618.8 5380.8 5389.6 5393.1 5396.2 5405.8 22:35:44 INFO - PID 4481 | 22:35:44 INFO - PID 4481 | [#1] ext.html Cycles:180 Average:30253.02 Median:2989.36 stddev:41252.96 (1380.0%) stddev-sans-first:41309.09 22:35:44 INFO - PID 4481 | Values: 721.8 756.7 774.1 1078.9 1093.3 32251.0 33308.5 33316.7 33335.8 33355.2 35296.3 35620.6 35655.0 35686.3 35782.8 6533.3 6669.6 6806.4 6886.6 6937.8 6690.0 6735.6 6765.8 6768.3 6792.5 6730.1 6783.9 6786.6 6786.8 6804.2 6731.4 6752.0 6772.0 6782.2 6784.1 6676.3 6696.0 6726.1 6743.5 6766.4 32142.4 32158.0 32189.5 32238.2 32284.0 2969.8 2989.8 2990.8 2993.5 3003.0 24437.3 24509.0 24611.5 24668.8 24673.8 2968.4 2975.9 2988.9 2990.6 3005.7 123803.4 126095.4 137936.0 141833.0 142479.0 39238.6 39491.2 39591.0 39674.6 39696.8 40340.0 40403.4 40558.0 40564.6 40569.8 12762.9 12922.8 12924.9 12965.9 12970.7 10466.3 10500.9 10507.7 10543.1 10563.2 12380.3 12401.0 12427.9 12549.8 12775.1 5195.4 5281.3 5306.1 5324.9 5339.8 839.6 877.8 880.5 889.4 907.8 19781.0 20395.6 21126.7 21263.1 21303.4 948.6 949.4 981.5 983.3 991.7 10397.5 11093.8 11151.7 11197.9 11342.0 179969.1 180794.0 182575.0 182602.0 183353.0 41157.6 41255.6 41344.2 41597.8 41726.4 1191.1 1218.6 1223.1 1225.4 1227.5 19276.6 19589.8 20194.3 20707.2 21199.7 29561.4 29646.9 29678.3 29684.6 29779.0 158095.0 159296.2 159330.0 159462.0 160113.0 28213.9 28312.4 28475.9 29415.4 29516.4 35623.6 36182.8 36232.6 36245.0 36258.6 26216.6 27399.6 27453.2 27590.6 28146.6 26879.9 27179.3 27412.7 27818.0 28431.0 30611.2 30657.2 31051.5 31067.4 31108.4 26398.2 26611.3 27056.9 27186.9 27978.4 21544.6 22132.4 22375.8 22545.8 22724.0 22:35:44 INFO - PID 4481 | 22:35:44 INFO - PID 4481 | [#2] jquery.html Cycles:180 Average:27379.66 Median:24102.16 stddev:64174.35 (266.3%) stddev-sans-first:64322.42 22:35:44 INFO - PID 4481 | Values: 408.4 416.3 430.0 433.7 438.6 17713.4 18315.4 18390.8 18393.3 20278.8 19597.5 19930.7 20052.0 20131.4 20139.9 5604.2 5734.2 5737.2 5742.0 5743.1 5264.8 5393.7 5428.9 5676.7 5716.8 5386.8 5401.0 5414.6 5414.8 5431.3 5373.9 5403.2 5405.6 5412.5 5422.3 4902.9 4903.6 4920.8 4926.0 4935.0 23429.9 23655.9 24020.6 24030.0 24174.3 1635.6 1673.1 1673.8 1676.2 1678.6 15382.2 15882.8 15916.7 15967.0 15982.4 2016.2 2078.3 2104.9 2118.3 2127.1 103251.0 105044.0 105888.5 105897.5 106038.5 10478.6 11056.5 11158.4 11377.9 11559.6 15846.7 15999.5 16010.4 16019.4 16022.9 7856.7 7917.2 7990.4 8050.4 8083.2 7296.3 7374.1 7492.9 7513.5 7818.6 7355.9 7473.0 7497.9 7532.2 7534.1 2053.6 2063.9 2108.1 2115.6 2176.6 783.8 835.1 838.8 857.2 862.3 21339.4 23381.0 23408.8 23575.4 23581.9 851.0 852.8 852.9 856.4 869.0 9702.0 9736.6 9808.1 10252.0 11868.5 376968.0 377698.0 381460.0 382719.0 386480.0 91217.5 92353.0 93199.5 94111.1 94276.0 1056.4 1085.8 1092.4 1102.5 1105.7 18595.3 18636.3 18807.6 19145.7 20402.7 45975.6 45989.5 46415.3 46523.5 46587.3 53265.0 53364.0 53435.5 53598.5 53676.5 9614.6 9825.8 9934.6 9956.3 9985.4 15859.2 15911.5 15934.6 15956.4 16067.8 13302.4 13804.8 13821.8 13852.6 14011.9 12338.2 12851.5 12888.9 13077.4 13167.5 13450.1 13497.1 13664.9 14295.3 14414.4 9563.9 9577.8 9625.0 9647.2 9671.7 13776.3 13885.8 13896.9 13899.0 13911.0 22:35:44 INFO - PID 4481 | 22:35:44 INFO - PID 4481 | [#3] mootools.html Cycles:180 Average:6258.47 Median:3832.14 stddev:4099.91 (107.0%) stddev-sans-first:4094.34 22:35:44 INFO - PID 4481 | Values: 1279.1 1332.2 1336.2 1348.8 1352.4 10145.3 10462.6 10593.5 10626.1 10736.0 10501.3 10568.0 10655.2 10658.9 10671.4 2138.2 2184.9 2185.1 2190.7 2199.7 2183.7 2295.9 2296.0 2297.6 2298.1 2138.3 2145.5 2158.7 2163.3 2166.5 2035.6 2058.4 2064.2 2077.6 2081.0 2075.1 2129.9 2138.4 2140.4 2140.5 9638.2 9710.6 9804.0 9805.1 9823.6 5448.6 5646.9 5662.3 5664.9 5667.2 3225.1 3228.6 3292.9 3359.9 3372.2 1012.8 1047.4 1063.8 1069.5 1073.0 14857.6 14969.6 15169.0 15193.0 15413.6 4909.6 4950.2 5059.8 5066.6 5099.6 11389.9 11391.1 11425.3 11464.4 11469.8 5373.3 5418.2 5426.5 5437.7 5439.8 4901.2 4948.9 4955.3 4960.4 4966.0 3615.5 3702.8 3718.9 3776.2 3777.4 1134.9 1147.5 1148.7 1166.0 1172.7 696.8 718.1 730.7 734.9 742.7 5431.4 5503.7 5578.3 5617.7 5637.0 539.8 543.3 546.9 547.3 566.6 3378.9 3886.9 4618.4 4741.7 4752.0 13311.9 13613.9 13626.3 13657.1 13659.2 12146.9 12857.8 13249.3 13475.7 13498.1 6931.9 7155.9 7302.9 7337.6 7350.1 5878.1 6080.5 6110.9 6251.8 6468.1 11721.7 12171.9 12180.8 12395.3 12559.0 12357.8 12541.3 12567.2 12762.2 12770.4 8091.5 8374.7 8415.2 8429.5 8452.0 9094.5 9149.6 9158.8 9159.2 9204.5 7583.3 7897.6 7904.7 7935.7 8129.2 7475.5 7504.4 7512.4 7703.4 7703.5 6820.0 6886.7 6946.8 6948.3 6952.6 4715.0 4727.5 4764.4 4828.2 4920.2 4840.6 4882.3 4908.6 5034.5 5081.1 22:35:44 INFO - PID 4481 | 22:35:44 INFO - PID 4481 | [#4] prototype.html Cycles:180 Average:4785.89 Median:5366.41 stddev:1389.58 (25.9%) stddev-sans-first:1353.52 22:35:44 INFO - PID 4481 | Values: 378.0 384.7 386.6 388.7 392.4 5389.5 5419.9 5460.0 5486.5 5554.8 5333.4 5410.5 5417.3 5424.1 5456.7 4075.7 4153.2 4183.2 4189.9 4212.6 4209.6 4212.3 4218.9 4219.0 4222.8 4168.8 4176.5 4188.4 4188.6 4203.2 4190.6 4199.5 4207.9 4209.4 4218.6 3729.6 3733.2 3735.7 3738.1 3746.3 5279.8 5326.8 5349.8 5355.8 5362.5 4956.6 4962.5 4965.3 4990.4 4992.6 5697.1 5734.3 5740.8 5745.9 5756.7 4159.3 4169.2 4172.9 4184.7 4189.4 5710.3 6065.0 6074.8 6110.3 6159.6 4180.5 4241.4 4256.1 4281.2 4282.6 4286.0 4301.4 4320.4 4320.7 4321.7 4720.8 4875.6 4896.9 4897.4 4911.8 5767.1 5772.6 5786.4 5789.7 5797.7 1467.9 1481.8 1485.2 1492.4 1497.8 981.6 1011.6 1032.4 1034.6 1040.2 4946.0 4957.7 4976.6 4994.7 5003.2 5953.8 5968.9 5977.3 5985.9 5995.6 4877.6 4921.4 4942.0 4960.0 4961.0 3095.0 3150.2 3164.7 3169.1 3172.8 5497.6 5510.9 5553.7 5574.5 5599.4 6077.1 6135.0 6141.2 6148.2 6170.8 5478.2 5522.6 5540.2 5552.9 5555.5 5918.8 5955.7 5965.5 5968.0 6012.3 5828.2 5851.4 5902.5 5916.7 5928.6 5302.3 5370.3 5430.9 5492.4 5539.4 4238.0 4248.7 4249.4 4251.2 4277.7 5876.6 5901.8 5923.5 5970.7 5980.1 5627.9 5708.8 5806.5 5834.1 5842.6 5815.9 5823.5 5823.7 5844.1 5844.9 5721.5 5727.9 5730.6 5744.6 5762.1 5832.2 5836.3 5848.2 5856.7 5883.2 5648.7 5663.6 5692.3 5708.3 5739.9 22:35:44 INFO - PID 4481 | 22:35:44 INFO - PID 4481 | [#5] yui.html Cycles:180 Average:17911.33 Median:4212.50 stddev:23643.55 (561.3%) stddev-sans-first:23674.71 22:35:44 INFO - PID 4481 | Values: 736.1 751.4 759.5 760.0 770.5 12501.9 13182.9 14187.4 14789.1 14925.6 14781.3 15400.8 15488.8 15492.5 15530.7 881.5 889.1 891.1 892.1 892.8 836.6 896.0 899.7 902.3 903.7 946.7 948.0 948.1 948.7 949.9 936.8 937.2 939.4 939.5 946.6 685.6 689.2 689.8 692.1 695.6 14925.3 15229.7 15334.2 15426.0 15429.2 15858.0 16304.2 16311.0 16335.2 16427.3 12490.0 12526.0 12982.8 12992.2 13030.9 2968.7 3000.9 3008.1 3010.4 3011.3 80603.6 82165.0 82272.2 82635.0 82962.6 12754.0 12775.7 12816.6 13002.0 13056.5 22717.3 22901.5 23005.4 23096.0 23122.5 6245.2 6449.3 6460.8 6502.3 6510.3 3010.4 3083.7 3087.8 3089.5 3093.5 3712.7 4151.0 4418.6 4436.3 4445.7 774.7 797.3 802.1 815.2 816.8 579.0 581.3 596.4 598.2 605.9 8305.0 8429.5 8588.4 8621.8 9072.5 576.3 578.5 582.6 592.9 601.5 3917.2 4050.4 4069.4 4204.6 4220.4 84512.0 86716.0 86914.0 87079.0 88339.1 73802.9 75604.0 77232.0 77324.6 77978.0 66706.3 67094.0 67258.0 67461.7 67706.0 8047.0 8052.7 8055.6 8070.6 8087.5 26570.9 27198.5 27238.6 27795.0 28528.0 49340.3 52472.7 53072.0 53161.7 53873.0 13885.7 13896.0 14207.0 14276.0 14304.9 15325.6 15482.7 15604.0 15646.6 15982.5 14001.9 14605.6 14857.0 16131.6 16372.8 13954.9 15328.9 15340.1 16012.2 16313.2 14945.7 15594.5 15690.8 15718.3 15795.2 10164.2 10369.2 10474.6 10523.9 11228.6 11305.4 12162.1 12171.7 12277.1 12398.8 22:35:44 INFO - PID 4481 | -------- Summary: end -------- 22:35:44 INFO - PID 4481 | 22:35:45 INFO - TEST-INFO | 4481: exit 0 22:35:45 INFO - TEST-OK | dromaeo_css | took 1146773ms 22:35:45 INFO - TEST-START | kraken 22:35:45 INFO - operating with platform_type : linux_ 22:35:45 INFO - Initialising browser for kraken test... 22:35:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39798/getInfo.html -profile /tmp/tmpjZJgSZ/profile 22:35:45 INFO - TEST-INFO | started process 4878 (/builds/slave/test/build/application/firefox/firefox http://localhost:39798/getInfo.html) 22:36:19 INFO - TEST-INFO | 4878: killed by SIGTERM 22:36:19 INFO - Browser initialized. 22:36:19 INFO - Running cycle 1/1 for kraken test... 22:36:19 INFO - TEST-INFO | started process 5085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjZJgSZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:36:31 INFO - PID 5085 | RSS: Main: 184516608 22:36:31 INFO - PID 5085 | 22:36:34 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39798/tests/kraken/driver.html?testName=audio-beat-detection) 22:36:35 INFO - PID 5085 | RSS: Main: 187785216 22:36:35 INFO - PID 5085 | 22:36:37 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39798/tests/kraken/driver.html?testName=audio-dft) 22:36:38 INFO - PID 5085 | RSS: Main: 186859520 22:36:38 INFO - PID 5085 | 22:36:41 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39798/tests/kraken/driver.html?testName=audio-fft) 22:36:42 INFO - PID 5085 | RSS: Main: 182038528 22:36:42 INFO - PID 5085 | 22:36:44 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39798/tests/kraken/driver.html?testName=audio-oscillator) 22:36:44 INFO - PID 5085 | RSS: Main: 182628352 22:36:44 INFO - PID 5085 | 22:36:47 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39798/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:36:47 INFO - PID 5085 | RSS: Main: 182657024 22:36:47 INFO - PID 5085 | 22:36:50 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39798/tests/kraken/driver.html?testName=imaging-darkroom) 22:36:51 INFO - PID 5085 | RSS: Main: 183853056 22:36:51 INFO - PID 5085 | 22:36:54 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39798/tests/kraken/driver.html?testName=imaging-desaturate) 22:36:55 INFO - PID 5085 | RSS: Main: 182951936 22:36:55 INFO - PID 5085 | 22:36:58 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39798/tests/kraken/driver.html?testName=json-parse-financial) 22:36:59 INFO - PID 5085 | RSS: Main: 183697408 22:36:59 INFO - PID 5085 | 22:37:00 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39798/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:37:01 INFO - PID 5085 | RSS: Main: 183664640 22:37:01 INFO - PID 5085 | 22:37:03 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39798/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:37:03 INFO - PID 5085 | RSS: Main: 183332864 22:37:03 INFO - PID 5085 | 22:37:06 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39798/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:37:06 INFO - PID 5085 | RSS: Main: 183177216 22:37:06 INFO - PID 5085 | 22:37:09 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39798/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:37:10 INFO - PID 5085 | RSS: Main: 183787520 22:37:10 INFO - PID 5085 | 22:37:13 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39798/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:37:14 INFO - PID 5085 | RSS: Main: 183570432 22:37:14 INFO - PID 5085 | 22:37:15 INFO - PID 5085 | Cycle 1(1): loaded http://localhost:39798/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39798/tests/kraken/driver.html?testName=ai-astar) 22:37:16 INFO - PID 5085 | RSS: Main: 184819712 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | __start_tp_report 22:37:16 INFO - PID 5085 | _x_x_mozilla_page_load 22:37:16 INFO - PID 5085 | _x_x_mozilla_page_load_details 22:37:16 INFO - PID 5085 | |i|pagename|runs| 22:37:16 INFO - PID 5085 | |0;ai-astar;102;113;112;111;110;111;111;110;110;110 22:37:16 INFO - PID 5085 | |1;audio-beat-detection;153;125;124;188;124;122;130;124;146;123 22:37:16 INFO - PID 5085 | |2;audio-dft;164;165;162;165;169;168;178;164;165;163 22:37:16 INFO - PID 5085 | |3;audio-fft;72;75;74;105;74;76;74;76;91;77 22:37:16 INFO - PID 5085 | |4;audio-oscillator;85;83;114;84;84;83;101;83;83;106 22:37:16 INFO - PID 5085 | |5;imaging-gaussian-blur;114;114;113;114;114;114;114;122;113;114 22:37:16 INFO - PID 5085 | |6;imaging-darkroom;152;151;152;151;151;151;150;150;149;151 22:37:16 INFO - PID 5085 | |7;imaging-desaturate;95;96;94;97;95;94;94;95;95;99 22:37:16 INFO - PID 5085 | |8;json-parse-financial;56;55;59;57;63;57;56;57;57;57 22:37:16 INFO - PID 5085 | |9;json-stringify-tinderbox;57;57;57;56;56;57;57;56;57;57 22:37:16 INFO - PID 5085 | |10;stanford-crypto-aes;89;87;88;88;89;89;91;91;117;89 22:37:16 INFO - PID 5085 | |11;stanford-crypto-ccm;131;132;134;132;136;134;131;130;133;147 22:37:16 INFO - PID 5085 | |12;stanford-crypto-pbkdf2;195;196;196;204;201;192;202;189;191;193 22:37:16 INFO - PID 5085 | |13;stanford-crypto-sha256-iterative;73;73;80;74;73;73;72;71;73;72 22:37:16 INFO - PID 5085 | __end_tp_report 22:37:16 INFO - PID 5085 | __start_cc_report 22:37:16 INFO - PID 5085 | _x_x_mozilla_cycle_collect,1050 22:37:16 INFO - PID 5085 | __end_cc_report 22:37:16 INFO - PID 5085 | __startTimestamp1496295436037__endTimestamp 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | ------- Summary: start ------- 22:37:16 INFO - PID 5085 | Number of tests: 14 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:2.98 (2.7%) stddev-sans-first:1.05 22:37:16 INFO - PID 5085 | Values: 102.0 113.0 112.0 111.0 110.0 111.0 111.0 110.0 110.0 110.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:127.50 stddev:21.21 (16.6%) stddev-sans-first:21.57 22:37:16 INFO - PID 5085 | Values: 153.0 125.0 124.0 188.0 124.0 122.0 130.0 124.0 146.0 123.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#2] audio-dft Cycles:10 Average:166.30 Median:165.00 stddev:4.62 (2.8%) stddev-sans-first:4.82 22:37:16 INFO - PID 5085 | Values: 164.0 165.0 162.0 165.0 169.0 168.0 178.0 164.0 165.0 163.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#3] audio-fft Cycles:10 Average:79.40 Median:75.50 stddev:10.44 (13.8%) stddev-sans-first:10.72 22:37:16 INFO - PID 5085 | Values: 72.0 75.0 74.0 105.0 74.0 76.0 74.0 76.0 91.0 77.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:83.00 stddev:11.75 (14.2%) stddev-sans-first:12.29 22:37:16 INFO - PID 5085 | Values: 85.0 83.0 114.0 84.0 84.0 83.0 101.0 83.0 83.0 106.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.00 stddev:2.63 (2.3%) stddev-sans-first:2.78 22:37:16 INFO - PID 5085 | Values: 114.0 114.0 113.0 114.0 114.0 114.0 114.0 122.0 113.0 114.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 22:37:16 INFO - PID 5085 | Values: 152.0 151.0 152.0 151.0 151.0 151.0 150.0 150.0 149.0 151.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:1.58 (1.7%) stddev-sans-first:1.67 22:37:16 INFO - PID 5085 | Values: 95.0 96.0 94.0 97.0 95.0 94.0 94.0 95.0 95.0 99.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:57.00 stddev:2.22 (3.9%) stddev-sans-first:2.30 22:37:16 INFO - PID 5085 | Values: 56.0 55.0 59.0 57.0 63.0 57.0 56.0 57.0 57.0 57.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#9] json-stringify-tinderbox Cycles:10 Average:56.70 Median:57.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 22:37:16 INFO - PID 5085 | Values: 57.0 57.0 57.0 56.0 56.0 57.0 57.0 56.0 57.0 57.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:89.00 stddev:8.94 (10.0%) stddev-sans-first:9.43 22:37:16 INFO - PID 5085 | Values: 89.0 87.0 88.0 88.0 89.0 89.0 91.0 91.0 117.0 89.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:133.50 stddev:4.90 (3.7%) stddev-sans-first:5.07 22:37:16 INFO - PID 5085 | Values: 131.0 132.0 134.0 132.0 136.0 134.0 131.0 130.0 133.0 147.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.90 Median:196.00 stddev:5.00 (2.6%) stddev-sans-first:5.29 22:37:16 INFO - PID 5085 | Values: 195.0 196.0 196.0 204.0 201.0 192.0 202.0 189.0 191.0 193.0 22:37:16 INFO - PID 5085 | 22:37:16 INFO - PID 5085 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:73.00 stddev:2.46 (3.4%) stddev-sans-first:2.60 22:37:16 INFO - PID 5085 | Values: 73.0 73.0 80.0 74.0 73.0 73.0 72.0 71.0 73.0 72.0 22:37:16 INFO - PID 5085 | -------- Summary: end -------- 22:37:16 INFO - PID 5085 | 22:37:17 INFO - TEST-INFO | 5085: exit 0 22:37:17 INFO - TEST-OK | kraken | took 92145ms 22:37:17 INFO - SUITE-END | took 1238s 22:37:17 INFO - Completed test suite (00:20:39) 22:37:17 INFO - javascript benchmark 22:37:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6863.011719979126, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2725.972740272597, 2747.491714032903, 2755.7382048705367, 2780.6663200415946, 2781.3046738315434, 5041.974790126044, 5246.816361427346, 5271.499207575283, 5293.682379057259, 5296.629235953485, 5186.299849520314, 5216.686998780076, 5260.316158899338, 5281.524662780349, 5292.179712144623, 4464.628437828691, 4635.46692130406, 4677.462091859437, 4707.6233901287815, 4710.246360582308, 4750.2874568814605, 4769.236922092466, 4774.474807771142, 4793.976030119845, 4802.783874725628, 4620.5841474267145, 4636.86089417318, 4693.530646935279, 4718.882027949295, 4719.126961512097, 4702.694324868872, 4703.129920964632, 4704.882377940545, 4724.0, 4750.596199323028, 4640.5127461616385, 4660.0, 4661.836835710767, 4665.346851440801, 4679.461861885893, 5122.974385128088, 5167.9224811627855, 5201.713905735183, 5226.268322434862, 5228.503292187236, 5678.460546248141, 5681.943180568224, 5716.399778023331, 5742.253507044099, 5760.567957403212, 5712.285964254468, 5714.685692286923, 5717.456841600041, 5723.54211663066, 5736.483716465496, 5230.843074707765, 5300.44345343741, 5300.946990530046, 5316.388615309212, 5373.543248823894, 5736.913946290809, 5750.6262092963825, 5849.385814489502, 5869.295684517885, 5900.881982360414, 5471.753771080311, 5542.667439953616, 5551.111822108443, 5613.326400831927, 5634.5210657093785, 5503.477169668875, 5663.292088488939, 5663.858403539862, 5678.488935995698, 5693.4875861172695, 5633.380328163898, 5639.8590035249445, 5641.0, 5658.773649054074, 5664.631798933056, 5480.835574932758, 5530.170474428868, 5547.833564993057, 5634.915476267859, 5661.575381846378, 4703.623710103184, 4704.129735998851, 4704.529547045337, 4732.337472753818, 4744.596709279681, 4003.659688926474, 4007.0, 4012.277789997828, 4059.6143366380147, 4077.796110194538, 4692.0850434165695, 4745.217039188564, 4755.334253204554, 4762.309465127606, 4790.808367665324, 5750.6262092963825, 5756.827295181151, 5758.798442054508, 5766.625169363978, 5771.971140144273, 4799.712017278975, 4810.230363141881, 4819.277108433763, 4821.276808478686, 4842.297866809364, 4849.830255941025, 4937.1113199624415, 4956.0, 5043.87390315245, 5079.1365467870555, 5271.0, 5299.523042926001, 5338.0, 5343.519083282367, 5352.090144675492, 5660.971695141498, 5685.886282274414, 5701.800436984686, 5708.743106560131, 5714.428557144252, 5185.662931909414, 5264.10510213257, 5286.524212820865, 5297.682139071668, 5309.548688361532, 5512.283403157564, 5569.10894256917, 5617.831465056055, 5634.690092044977, 5645.830625081254, 5755.3381361144, 5762.711864406847, 5779.7110144493445, 5783.421657834183, 5832.737526811219, 5341.679499230058, 5419.918701219406, 5453.809116680897, 5546.861328466659, 5574.9442505574425, 5481.643693159932, 5527.115661494142, 5579.023670857665, 5581.358143813513, 5601.103823388238, 5337.0, 5408.7566059526625, 5423.078076726887, 5469.972650136724, 5482.259894914295, 5494.1758736189895, 5519.420460851553, 5556.638818476786, 5588.636738611977, 5618.831435056954, 5380.757865896122, 5389.622726409114, 5393.1370980642905, 5396.1905714143195, 5405.756675964629], "value": 5136.906127946368, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.801504586239, 756.6595032235493, 774.1175060431108, 1078.8813230544645, 1093.3494570730413, 32251.0, 33308.50037249443, 33316.66683333166, 33335.83332083339, 33355.16612084699, 35296.2940741185, 35620.643793562056, 35655.0, 35686.2862742745, 35782.82108589453, 6533.281339052701, 6669.566478178927, 6806.3533964273565, 6886.621235832027, 6937.7571784987285, 6690.0, 6735.5621884577595, 6765.797026089225, 6768.3231676832165, 6792.524523283372, 6730.0577919091365, 6783.932160678404, 6786.55887367322, 6786.796396108125, 6804.18349798025, 6731.360520750521, 6752.0, 6771.966140169267, 6782.220044694849, 6784.118064651613, 6676.298988606175, 6696.0, 6726.058351830748, 6743.460523158135, 6766.424853887376, 32142.357152857046, 32158.0, 32189.51715724266, 32238.194045148823, 32284.031479055666, 2969.8069625474277, 2989.7757668174754, 2990.820550766939, 2993.476141675198, 3002.9549556756665, 24437.266881993568, 24509.0, 24611.50776984468, 24668.753312466826, 24673.753262467326, 2968.391479746668, 2975.851207439641, 2988.9402211955858, 2990.5794747494983, 3005.6693763685976, 123803.38098309541, 126095.36952315271, 137935.999999999, 141833.0, 142479.0, 39238.607613923494, 39491.210175796325, 39591.0, 39674.60325396767, 39696.801515992236, 40340.0, 40403.39394909079, 40558.0, 40564.59435405667, 40569.797151014645, 12762.9361853192, 12922.806157907638, 12924.870751292367, 12965.870341296466, 12970.675733106747, 10466.267361284788, 10500.947495262628, 10507.684769456928, 10543.103836173857, 10563.20775941792, 12380.257184568954, 12401.0, 12427.93786031064, 12549.811752823714, 12775.105742598114, 5195.376554813447, 5281.313429254173, 5306.124489459196, 5324.866878327995, 5339.839804805862, 839.5927974932154, 877.8288233794476, 880.5201165364891, 889.4040992535017, 907.7821322882461, 19781.0, 20395.592088158155, 21126.683099753514, 21263.149474020865, 21303.36089917305, 948.5778828421425, 949.4255975135083, 981.4945303168873, 983.2674657380297, 991.7124034030195, 10397.532111055021, 11093.83359249612, 11151.72120696989, 11197.888021119685, 11342.0, 179969.100154501, 180794.0, 182575.0, 182602.0, 183353.0, 41157.58842411597, 41255.58744412517, 41344.1731165375, 41597.79201103975, 41726.37410438846, 1191.142377488207, 1218.585680868509, 1223.0643557678384, 1225.4179264849126, 1227.4783217132683, 19276.61446771085, 19589.80410195937, 20194.29319973794, 20707.171713131305, 21199.68200476963, 29561.40877182401, 29646.851765741034, 29678.258043549085, 29684.55473167861, 29779.0, 158095.0, 159296.20351898167, 159330.0, 159462.0, 160113.0, 28213.858930705213, 28312.433751324446, 28475.85762071259, 29415.411691766472, 29516.409671806872, 35623.64376356307, 36182.81908590545, 36232.63767362398, 36245.0, 36258.637413625525, 26216.60675089837, 27399.589006165323, 27453.17640470789, 27590.586141207496, 28146.577801332587, 26879.86560067187, 27179.320516987234, 27412.725872741816, 27818.0, 28431.0, 30611.2347191322, 30657.233569160948, 31051.534226987063, 31067.378652427276, 31108.377832443675, 26398.20805375842, 26611.33471663224, 27056.864715676296, 27186.864065679543, 27978.440431190855, 21544.569108618052, 22132.446688832908, 22375.776242238022, 22545.774542254367, 22724.0], "value": 14400.091737453857, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.3935355996345, 416.33594416905026, 429.9806508707108, 433.73325404876, 438.59430027224806, 17713.380031698893, 18315.358962436316, 18390.81609183908, 18393.264269429223, 20278.797212027876, 19597.51006224845, 19930.70103948442, 20052.0, 20131.396058118244, 20139.899300503515, 5604.159376093588, 5734.168545560891, 5737.225474560943, 5741.971290143543, 5743.138529220609, 5264.842054738364, 5393.6763794172475, 5428.9185662215095, 5676.716164191795, 5716.828495145152, 5386.78452861887, 5400.972995135019, 5414.648047876895, 5414.7834086636485, 5431.34823821142, 5373.865653358658, 5403.162509810985, 5405.648632838853, 5412.539934105605, 5422.267993820843, 4902.877428064291, 4903.558679718807, 4920.7539623019065, 4926.0394223126505, 4934.950650493485, 23429.882850585807, 23655.88172059146, 24020.63969040466, 24030.038798448215, 24174.274771756875, 1635.6074542109927, 1673.1383337581156, 1673.8493535581856, 1676.1702957036346, 1678.6474840283556, 15382.230888455533, 15882.841171588252, 15916.681666366723, 15967.041977481389, 15982.360705571764, 2016.1632922337212, 2078.3349328214977, 2104.873707577535, 2118.343313572782, 2127.053461209762, 103251.0, 105044.0, 105888.4705576475, 105897.47051264695, 106038.46980765047, 10478.63324783629, 11056.502457389437, 11158.386288754196, 11377.943110284396, 11559.595414160633, 15846.683066338608, 15999.520014399586, 16010.359585616445, 16019.43931962399, 16022.919885400499, 7856.685732570748, 7917.208279172152, 7990.4406691532195, 8050.436469447082, 8083.151269116775, 7296.270372962767, 7374.078240219973, 7492.887606685904, 7513.549187048795, 7818.648160832776, 7355.880706804207, 7472.962635186898, 7497.887531687029, 7532.209118042636, 7534.095908490906, 2053.5687505623687, 2063.9484012899497, 2108.125128071833, 2115.6403411420097, 2176.614122543077, 783.8275579372529, 835.1314632781961, 838.8070743728977, 857.1985193843776, 862.3316929379781, 21339.35981920545, 23381.0, 23408.765912340998, 23575.41061473477, 23581.88209058944, 850.995745021271, 852.8251708399763, 852.9189726975927, 856.4005196362571, 868.9695860644847, 9702.029797020383, 9736.561854716554, 9808.117269445784, 10252.0, 11868.525258989717, 376968.0, 377698.0, 381460.0, 382719.0, 386480.0, 91217.54391228002, 92353.0, 93199.53400232956, 94111.0588894116, 94276.0, 1056.434807378049, 1085.7882712871071, 1092.3718861654422, 1102.536934487517, 1105.7069876482738, 18595.349162779778, 18636.254549817855, 18807.623847523246, 19145.712814308074, 20402.693959590913, 45975.61642881669, 45989.540104599866, 46415.30377044279, 46523.53476465192, 46587.30119048149, 53265.0, 53364.0, 53435.46564534305, 53598.46401535935, 53676.46323536715, 9614.567344469655, 9825.754356141153, 9934.602615895283, 9956.303058785816, 9985.350952188084, 15859.207039648201, 15911.522654320388, 15934.60980852835, 15956.36719941243, 16067.839321606634, 13302.401391937192, 13804.792928106288, 13821.792673110112, 13852.584422467342, 14011.859881401055, 12338.198017128858, 12851.485940562272, 12888.871111288767, 13077.41151648159, 13167.53913613019, 13450.12574182675, 13497.122687025312, 13664.93167534156, 14295.28523573838, 14414.351354188877, 9563.904360956301, 9577.760555985877, 9625.0, 9647.228221742384, 9671.70984870455, 13776.311184440938, 13885.791713124108, 13896.930515347358, 13899.0, 13911.0], "value": 9638.0181430004, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1279.1301914698008, 1332.1540821578299, 1336.2383441438385, 1348.7639663058962, 1352.3981828086498, 10145.340552864069, 10462.633807816728, 10593.523291451884, 10626.14990800736, 10736.0, 10501.264911456183, 10568.048875601195, 10655.200859935498, 10658.893411065887, 10671.359718416881, 2138.2409244718133, 2184.8798316092607, 2185.0604240176717, 2190.7371115466167, 2199.670049492573, 2183.650615901456, 2295.8622482651094, 2295.9655605165935, 2297.6094064009076, 2298.0577963035157, 2138.337115494202, 2145.5172586168064, 2158.6546152615583, 2163.3293678959494, 2166.490874644457, 2035.6132334856477, 2058.433930669063, 2064.1846470644164, 2077.594869000538, 2080.9895950520227, 2075.0869617368307, 2129.8935053247274, 2138.4119367173994, 2140.3792900058966, 2140.4862832920144, 9638.180754635865, 9710.61157553697, 9804.01959804021, 9805.068518490732, 9823.557939892722, 5448.645838020556, 5646.915296270559, 5662.320521537401, 5664.915026274609, 5667.234923285386, 3225.080851957181, 3228.580284563015, 3292.9176770580925, 3359.8992030239133, 3372.207531230159, 1012.8480727890802, 1047.3820445936933, 1063.803196408667, 1069.5400977579638, 1073.0342691577564, 14857.554273371816, 14969.550913472613, 15169.0, 15193.0, 15413.614659633598, 4909.582685471703, 4950.18321976877, 5059.822906198339, 5066.569341605931, 5099.59203263738, 11389.94305028486, 11391.088712902947, 11425.257358271685, 11464.4267786612, 11469.827952580552, 5373.328333958255, 5418.241446197536, 5426.457354264542, 5437.728113594305, 5439.782408703687, 4901.166801643729, 4948.90102197954, 4955.281484184773, 4960.379952505937, 4965.975170124127, 3615.5299811024397, 3702.7593206441493, 3718.851245950186, 3776.244751049801, 3777.376732839051, 1134.8865113488584, 1147.512307269407, 1148.7243061665308, 1165.9591914283128, 1172.6775136837439, 696.8188271049563, 718.105958082191, 730.7369347035063, 734.9485536012535, 742.7289039500652, 5431.402545719968, 5503.724813759296, 5578.30271216098, 5617.747201375948, 5636.97181514098, 539.7840863654491, 543.3371287029818, 546.907025805614, 547.3322546493272, 566.5722379603455, 3378.9493157602656, 3886.9028274292805, 4618.422697162855, 4741.691790033637, 4751.976240118777, 13311.933440332736, 13613.863861361458, 13626.318684065756, 13657.112287701466, 13659.248741319323, 12146.878531214752, 12857.807132893015, 13249.337533123306, 13475.663108422368, 13498.122622029538, 6931.930680693128, 7155.856882862314, 7302.890456643155, 7337.59643219628, 7350.081239845126, 5878.147668587945, 6080.543959202989, 6110.908336375047, 6251.843703907439, 6468.126802881551, 11721.706957326134, 12171.878281217429, 12180.756384872077, 12395.318257495925, 12558.995280377365, 12357.764223577571, 12541.310227937187, 12567.245965242115, 12762.234265944073, 12770.36148192568, 8091.514509129471, 8374.701921202224, 8415.242628163494, 8429.4942303462, 8452.0, 9094.499802510925, 9149.63401463934, 9158.77103072402, 9159.175674189355, 9204.539773011456, 7583.317501424898, 7897.565633890191, 7904.723334683258, 7935.722249721232, 8129.18708129206, 7475.476716629783, 7504.437167212372, 7512.361449276873, 7703.422243331661, 7703.537787732754, 6820.0, 6886.655667216719, 6946.79159625212, 6948.33990770876, 6952.617606031515, 4714.952850471452, 4727.4799772025735, 4764.4282686077895, 4828.179209534317, 4920.21276595743, 4840.588549973291, 4882.340883980617, 4908.631852610997, 5034.471380504994, 5081.060003899291], "value": 4657.714111733728, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.99811000944993, 384.72684394080204, 386.61338661338664, 388.6696308138081, 392.36241108199175, 5389.469897217324, 5419.918701219485, 5459.972700136499, 5486.451354864516, 5554.777808887649, 5333.38666053404, 5410.540104091147, 5417.26866872972, 5424.1321388577835, 5456.727163641822, 4075.6943229257777, 4153.210889930919, 4183.184279065584, 4189.895252618679, 4212.578742125778, 4209.6421804146685, 4212.304969679999, 4218.915621687564, 4219.008532994744, 4222.7753951354225, 4168.832726836476, 4176.540580536153, 4188.371744238359, 4188.5968200652815, 4203.222403855297, 4190.601892820192, 4199.45407097077, 4207.936880946788, 4209.410682504452, 4218.641415479688, 3729.627037296276, 3733.178700685845, 3735.7198210134347, 3738.102855314732, 3746.2507498500136, 5279.782491353641, 5326.8305358311745, 5349.796295833446, 5355.758990845421, 5362.517373436371, 4956.553910148104, 4962.478939711351, 4965.329680493123, 4990.351254336949, 4992.550670439678, 5697.088465845486, 5734.283214598176, 5740.7703691852275, 5745.942540574624, 5756.697321071563, 4159.292920203573, 4169.207850508394, 4172.91654166918, 4184.727992680466, 4189.413482112506, 5710.2576665033275, 6065.0, 6074.787382441637, 6110.297315808739, 6159.599626024294, 4180.456540649696, 4241.448611680462, 4256.106217694257, 4281.207976524353, 4282.550332215135, 4285.957140428618, 4301.440812694393, 4320.416743739618, 4320.675949303814, 4321.719088259316, 4720.811167553328, 4875.634327425457, 4896.853094407174, 4897.436794768647, 4911.803527858917, 5767.077267637158, 5772.595918285764, 5786.42135786418, 5789.652620842763, 5797.652140871562, 1467.9192644404661, 1481.8073650425376, 1485.1831492678984, 1492.417956996772, 1497.827749808786, 981.6440542697583, 1011.5953618552638, 1032.3702541449709, 1034.632705389591, 1040.152275895143, 4945.950540494621, 4957.652964292537, 4976.576990955737, 4994.725290109079, 5003.174476211457, 5953.814709130595, 5968.85077873048, 5977.252843394575, 5985.910211346833, 5995.580309378389, 4877.585405240523, 4921.384826896638, 4942.0, 4959.975200123977, 4960.975195124001, 3095.0, 3150.212446888278, 3164.746820254374, 3169.112648458435, 3172.762042846796, 5497.5601951843755, 5510.9448905511235, 5553.694546799965, 5574.470425309589, 5599.356074051455, 6077.1491991121275, 6134.98772702508, 6141.201643786369, 6148.1699970503405, 6170.845728856814, 5478.178273258964, 5522.6134170608475, 5540.2243685884005, 5552.888942221133, 5555.50000499957, 5918.822435326947, 5955.67243801595, 5965.463108320272, 5968.045112782108, 6012.278526576839, 5828.184054232326, 5851.414858514115, 5902.498287645598, 5916.7041647918295, 5928.614640048383, 5302.28419163408, 5370.301860758076, 5430.945690543202, 5492.423295554069, 5539.446055394428, 4238.0, 4248.702590818737, 4249.447571815768, 4251.234777739913, 4277.6791740618955, 5876.647401155944, 5901.763929442946, 5923.49650279731, 5970.671613061149, 5980.1328807321825, 5627.943720562742, 5708.771649133988, 5806.506446952057, 5834.124881267844, 5842.561807864512, 5815.912761308668, 5823.475887170175, 5823.70881455934, 5844.12338149281, 5844.94155058444, 5721.542276617777, 5727.8854422912145, 5730.627509211888, 5744.5978781486565, 5762.135679648086, 5832.154337621106, 5836.270466191726, 5848.239728835394, 5856.707164641836, 5883.176355310175, 5648.745806438802, 5663.631863928831, 5692.345380281155, 5708.257926469532, 5739.8565035874435], "value": 4360.634469129803, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.1093077376374, 751.3951269228272, 759.4683721395024, 759.9506032107912, 770.5222761887629, 12501.937180365228, 13182.945364370851, 14187.361568729406, 14789.1126532408, 14925.552233433002, 14781.260936953136, 15400.845991540082, 15488.84511154891, 15492.5352239433, 15530.689386212269, 881.5063564403954, 889.1375365895071, 891.0910870911666, 892.1346294094718, 892.8258989497051, 836.6109758962082, 895.9964839379903, 899.6806133822464, 902.3277658144691, 903.6882275614902, 946.6544711180404, 948.0330063335393, 948.127722495306, 948.6821914658598, 949.924006079512, 936.7798567336671, 937.1706040154444, 939.3518472254123, 939.5161491831713, 946.5835032585675, 685.5817951049855, 689.1557841643996, 689.8068540808582, 692.0622556436057, 695.6347917343385, 14925.25373731309, 15229.695406091816, 15334.23328833554, 15426.0, 15429.151396673178, 15858.0, 16304.184790760533, 16311.0, 16335.183240838029, 16427.260773265232, 12490.0, 12526.0, 12982.805257921138, 12992.220466772023, 13030.934845325808, 2968.7031296870355, 3000.8949686761075, 3008.127642983532, 3010.3979204159036, 3011.3073992981517, 80603.5969820153, 82165.0, 82272.17727822706, 82635.0, 82962.58518707368, 12754.0, 12775.680607984688, 12816.551420700418, 13002.0, 13056.543020994219, 22717.318480445614, 22901.541969160524, 23005.424864378525, 23096.0, 23122.537549249257, 6245.219347581552, 6449.290578036413, 6460.806175814733, 6502.317256688074, 6510.25132109804, 3010.443068032421, 3083.7378822799865, 3087.768417368706, 3089.5056790913345, 3093.474109401407, 3712.7029837612927, 4150.979245103756, 4418.55814418562, 4436.290193569013, 4445.711028783118, 774.6707649249047, 797.2824457987857, 802.1016461563087, 815.2255357410484, 816.7794695432319, 578.9681567513827, 581.2501872584403, 596.3827438601027, 598.2312728144313, 605.9333473317931, 8305.0, 8429.452085614415, 8588.441751286271, 8621.78445538854, 9072.501012444247, 576.2623841482908, 578.4764787866987, 582.621296157501, 592.8754961458056, 601.5458328961606, 3917.216556688674, 4050.3924411338535, 4069.3895915612893, 4204.642605378516, 4220.366944958281, 84512.0, 86716.0, 86914.0, 87079.0, 88339.11660883437, 73802.89295660569, 75604.0, 77232.0, 77324.61337693276, 77978.0, 66706.33293667196, 67094.0, 67258.0, 67461.6626916872, 67706.0, 8046.959765201371, 8052.677892884219, 8055.597220138852, 8070.636821342936, 8087.47431416956, 26570.86714566415, 27198.456030879668, 27238.591421129095, 27795.0, 28528.0, 49340.25989610087, 52472.737636311576, 53072.0, 53161.734191330346, 53873.0, 13885.652858678613, 13896.027278090436, 14207.0, 14276.0, 14304.856951430353, 15325.616859578155, 15482.690346193238, 15604.0, 15646.608834779221, 15982.520524384287, 14001.929990349983, 14605.561833145022, 14857.0, 16131.596710082342, 16372.83627163713, 13954.930225348808, 15328.923355383151, 15340.079595224322, 16012.199390030219, 16313.184340783151, 14945.701085978002, 15594.454194103151, 15690.843091568939, 15718.292676829342, 15795.21023948775, 10164.18686505063, 10369.170466362522, 10474.633387831389, 10523.894761052597, 11228.55085796559, 11305.43472826339, 12162.148649594443, 12171.695707607381, 12277.140600157903, 12398.752024959269], "value": 7215.695678548277, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 112.0, 111.0, 110.0, 111.0, 111.0, 110.0, 110.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 125.0, 124.0, 188.0, 124.0, 122.0, 130.0, 124.0, 146.0, 123.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 162.0, 165.0, 169.0, 168.0, 178.0, 164.0, 165.0, 163.0], "value": 166.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 74.0, 105.0, 74.0, 76.0, 74.0, 76.0, 91.0, 77.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 114.0, 84.0, 84.0, 83.0, 101.0, 83.0, 83.0, 106.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 122.0, 113.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 152.0, 151.0, 151.0, 151.0, 150.0, 150.0, 149.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 94.0, 97.0, 95.0, 94.0, 94.0, 95.0, 95.0, 99.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 59.0, 57.0, 63.0, 57.0, 56.0, 57.0, 57.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 56.0, 56.0, 57.0, 57.0, 56.0, 57.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 88.0, 89.0, 89.0, 91.0, 91.0, 117.0, 89.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 132.0, 134.0, 132.0, 136.0, 134.0, 131.0, 130.0, 133.0, 147.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 196.0, 196.0, 204.0, 201.0, 192.0, 202.0, 189.0, 191.0, 193.0], "value": 195.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 80.0, 74.0, 73.0, 73.0, 72.0, 71.0, 73.0, 72.0], "value": 73.4, "unit": "score"}]}]} 22:37:17 INFO - Return code: 0 22:37:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:37:17 INFO - # TBPL SUCCESS # 22:37:17 INFO - Running post-action listener: _resource_record_post_action 22:37:17 INFO - [mozharness: 2017-06-01 05:37:17.848554Z] Finished run-tests step (success) 22:37:17 INFO - Running post-run listener: _resource_record_post_run 22:37:17 INFO - Total resource usage - Wall time: 1255s; CPU: 13.0%; Read bytes: 438272; Write bytes: 416460800; Read time: 244; Write time: 240564 22:37:17 INFO - TinderboxPrint: CPU usage
12.6% 22:37:17 INFO - TinderboxPrint: I/O read bytes / time
438,272 / 244 22:37:17 INFO - TinderboxPrint: I/O write bytes / time
416,460,800 / 240,564 22:37:17 INFO - TinderboxPrint: CPU idle
8,751.5 (87.4%) 22:37:17 INFO - TinderboxPrint: CPU user
1,217.5 (12.2%) 22:37:17 INFO - TinderboxPrint: Swap in / out
0 / 0 22:37:17 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 105406464; Read time: 52; Write time: 85784 22:37:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 22:37:17 INFO - run-tests - Wall time: 1240s; CPU: 13.0%; Read bytes: 434176; Write bytes: 311054336; Read time: 192; Write time: 154780 22:37:18 INFO - Running post-run listener: _upload_blobber_files 22:37:18 INFO - Blob upload gear active. 22:37:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:37:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:37:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:37:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:37:18 INFO - (blobuploader) - INFO - Open directory for files ... 22:37:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:37:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:37:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:19 INFO - (blobuploader) - INFO - Done attempting. 22:37:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:37:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:37:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:20 INFO - (blobuploader) - INFO - Done attempting. 22:37:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:37:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:37:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:23 INFO - (blobuploader) - INFO - Done attempting. 22:37:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:37:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:37:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:24 INFO - (blobuploader) - INFO - Done attempting. 22:37:24 INFO - (blobuploader) - INFO - Iteration through files over. 22:37:24 INFO - Return code: 0 22:37:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:37:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:37:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59bdfe130145a101ef10d939882628bd06384cf9b28ad7d624b7442dc3b6939d047be4fce079e169d1d3f2c0ffe960b43ac6d1acd4c9c9c63542813eb8b57167", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/389bfbd6b8c27db73fd176c72263d5974db44c49873a326b255640b135ad1f1ff812e27548f62ced052a9ac69756ded0d794a8e4af95ac5dae607bdb297196f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a6712a875efe431cf04d490891b16c8566ef087b1aedc8040c768d3c771c94527f839527c5f82e8e9d00dfc59e08c6f6be3909074104b48cec9001af682d0612", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5fcca3811e1f694a37e2a993122c0d5ae52a7e38ac9821d32724d4a6435cf3cb0a711ac3349ec08381fcb4d666a5c5dba3156817a6cc8ce55a25593d07ce28e5"} 22:37:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:37:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:37:24 INFO - Contents: 22:37:24 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59bdfe130145a101ef10d939882628bd06384cf9b28ad7d624b7442dc3b6939d047be4fce079e169d1d3f2c0ffe960b43ac6d1acd4c9c9c63542813eb8b57167", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/389bfbd6b8c27db73fd176c72263d5974db44c49873a326b255640b135ad1f1ff812e27548f62ced052a9ac69756ded0d794a8e4af95ac5dae607bdb297196f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a6712a875efe431cf04d490891b16c8566ef087b1aedc8040c768d3c771c94527f839527c5f82e8e9d00dfc59e08c6f6be3909074104b48cec9001af682d0612", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5fcca3811e1f694a37e2a993122c0d5ae52a7e38ac9821d32724d4a6435cf3cb0a711ac3349ec08381fcb4d666a5c5dba3156817a6cc8ce55a25593d07ce28e5"} 22:37:24 INFO - Running post-run listener: copy_logs_to_upload_dir 22:37:24 INFO - Copying logs to upload dir... 22:37:24 INFO - mkdir: /builds/slave/test/build/upload/logs 22:37:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1316.185321 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 56 secs) (at 2017-05-31 22:37:24.191435) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 22:37:24.198758) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59bdfe130145a101ef10d939882628bd06384cf9b28ad7d624b7442dc3b6939d047be4fce079e169d1d3f2c0ffe960b43ac6d1acd4c9c9c63542813eb8b57167", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/389bfbd6b8c27db73fd176c72263d5974db44c49873a326b255640b135ad1f1ff812e27548f62ced052a9ac69756ded0d794a8e4af95ac5dae607bdb297196f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a6712a875efe431cf04d490891b16c8566ef087b1aedc8040c768d3c771c94527f839527c5f82e8e9d00dfc59e08c6f6be3909074104b48cec9001af682d0612", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5fcca3811e1f694a37e2a993122c0d5ae52a7e38ac9821d32724d4a6435cf3cb0a711ac3349ec08381fcb4d666a5c5dba3156817a6cc8ce55a25593d07ce28e5"} build_url:https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011203 build_url: 'https://queue.taskcluster.net/v1/task/QZZGBCr1SxyzA6SLRv1CMg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59bdfe130145a101ef10d939882628bd06384cf9b28ad7d624b7442dc3b6939d047be4fce079e169d1d3f2c0ffe960b43ac6d1acd4c9c9c63542813eb8b57167", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/389bfbd6b8c27db73fd176c72263d5974db44c49873a326b255640b135ad1f1ff812e27548f62ced052a9ac69756ded0d794a8e4af95ac5dae607bdb297196f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a6712a875efe431cf04d490891b16c8566ef087b1aedc8040c768d3c771c94527f839527c5f82e8e9d00dfc59e08c6f6be3909074104b48cec9001af682d0612", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5fcca3811e1f694a37e2a993122c0d5ae52a7e38ac9821d32724d4a6435cf3cb0a711ac3349ec08381fcb4d666a5c5dba3156817a6cc8ce55a25593d07ce28e5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 22:37:24.225329) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 22:37:24.225707) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1496279716.388633-1950000154 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004413 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 22:37:24.305278) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 22:37:24.307370) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 22:37:24.307705) ========= ========= Total master_lag: 0.15 =========