builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-003 starttime: 1494891514.44 results: success (0) revision: a4235c4be96edaf90b5d6d7c20272a8761ca2339 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:34.442552) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:34.443138) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:34.443409) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:34.480017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:34.480270) ========= 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=7de08804244eb36560b44971000001c3-1494891140.6873-252914416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-15 16:38:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.2M=0s 2017-05-15 16:38:34 (37.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149140 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:34.656114) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:34.656418) ========= 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=7de08804244eb36560b44971000001c3-1494891140.6873-252914416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017635 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:34.719157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 16:38:34.719451) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a4235c4be96edaf90b5d6d7c20272a8761ca2339 --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 a4235c4be96edaf90b5d6d7c20272a8761ca2339 --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=7de08804244eb36560b44971000001c3-1494891140.6873-252914416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-15 16:38:34,635 truncating revision to first 12 chars 2017-05-15 16:38:34,635 Setting DEBUG logging. 2017-05-15 16:38:34,635 attempt 1/10 2017-05-15 16:38:34,635 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a4235c4be96e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 16:38:35,378 unpacking tar archive at: mozilla-central-a4235c4be96e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.121261 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 16:38:35.854165) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:35.854472) ========= script_repo_revision: a4235c4be96edaf90b5d6d7c20272a8761ca2339 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:35.857915) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:35.858181) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 16:38:35.868551) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-05-15 16:38:35.868828) ========= /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=7de08804244eb36560b44971000001c3-1494891140.6873-252914416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:38:35 INFO - MultiFileLogger online at 20170515 16:38:35 in /builds/slave/test 16:38:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:38:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:38:35 INFO - {'append_to_log': False, 16:38:35 INFO - 'base_work_dir': '/builds/slave/test', 16:38:35 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:38:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:38:35 INFO - 'branch': 'Firefox-Non-PGO', 16:38:35 INFO - 'buildbot_json_path': 'buildprops.json', 16:38:35 INFO - 'config_files': ('talos/linux_config.py',), 16:38:35 INFO - 'default_actions': ('clobber', 16:38:35 INFO - 'read-buildbot-config', 16:38:35 INFO - 'download-and-extract', 16:38:35 INFO - 'populate-webroot', 16:38:35 INFO - 'create-virtualenv', 16:38:35 INFO - 'install', 16:38:35 INFO - 'run-tests'), 16:38:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:38:35 INFO - 'download_minidump_stackwalk': True, 16:38:35 INFO - 'download_symbols': 'ondemand', 16:38:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:38:35 INFO - 'tooltool.py': '/tools/tooltool.py', 16:38:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:38:35 INFO - '/tools/misc-python/virtualenv.py')}, 16:38:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:38:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:38:35 INFO - 'gecko_profile': False, 16:38:35 INFO - 'gecko_profile_interval': 0, 16:38:35 INFO - 'installer_path': 'installer.exe', 16:38:35 INFO - 'log_level': 'info', 16:38:35 INFO - 'log_name': 'talos', 16:38:35 INFO - 'log_to_console': True, 16:38:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:38:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:38:35 INFO - 'opt_config_files': (), 16:38:35 INFO - 'pip_index': False, 16:38:35 INFO - 'suite': 'dromaeojs-e10s', 16:38:35 INFO - 'system_bits': '32', 16:38:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:38:35 INFO - 'title': 'talos-linux64-ix-003', 16:38:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:38:35 INFO - 'use_talos_json': True, 16:38:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:38:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:38:35 INFO - 'work_dir': 'build'} 16:38:35 INFO - [mozharness: 2017-05-15 23:38:35.889241Z] Running clobber step. 16:38:35 INFO - Running pre-action listener: _resource_record_pre_action 16:38:35 INFO - Running main action method: clobber 16:38:35 INFO - rmtree: /builds/slave/test/build 16:38:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:38:36 INFO - Running post-action listener: _resource_record_post_action 16:38:36 INFO - [mozharness: 2017-05-15 23:38:36.896166Z] Finished clobber step (success) 16:38:36 INFO - [mozharness: 2017-05-15 23:38:36.896267Z] Running read-buildbot-config step. 16:38:36 INFO - Running pre-action listener: _resource_record_pre_action 16:38:36 INFO - Running main action method: read_buildbot_config 16:38:36 INFO - Using buildbot properties: 16:38:36 INFO - { 16:38:36 INFO - "project": "", 16:38:36 INFO - "product": "firefox", 16:38:36 INFO - "who": "kwierso@gmail.com", 16:38:36 INFO - "installer_path": "public/build/target.tar.bz2", 16:38:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:38:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:38:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:38:36 INFO - "stage_platform": "linux64", 16:38:36 INFO - "basedir": "/builds/slave/test", 16:38:36 INFO - "buildnumber": 605, 16:38:36 INFO - "platform": "ubuntu64_hw", 16:38:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:38:36 INFO - "slavebuilddir": "test", 16:38:36 INFO - "taskId": "KKrOBB8wS3-pwvs1kFYJWg", 16:38:36 INFO - "branch": "mozilla-central", 16:38:36 INFO - "script_repo_revision": "production", 16:38:36 INFO - "revision": "a4235c4be96edaf90b5d6d7c20272a8761ca2339", 16:38:36 INFO - "slavename": "talos-linux64-ix-003", 16:38:36 INFO - "repo_path": "mozilla-central" 16:38:36 INFO - } 16:38:36 INFO - Finding installer, test and symbols from parent task. 16:38:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KKrOBB8wS3-pwvs1kFYJWg'}, attempt #1 16:38:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KKrOBB8wS3-pwvs1kFYJWg'}, attempt #1 16:38:37 INFO - Task dependencies: YP_-BW02Rby3vzJ-SvVoRQ 16:38:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ'}, attempt #1 16:38:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.tar.bz2 16:38:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/test_packages.json 16:38:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.crashreporter-symbols.zip 16:38:38 INFO - Running post-action listener: _resource_record_post_action 16:38:38 INFO - [mozharness: 2017-05-15 23:38:38.284510Z] Finished read-buildbot-config step (success) 16:38:38 INFO - [mozharness: 2017-05-15 23:38:38.284731Z] Running download-and-extract step. 16:38:38 INFO - Running pre-action listener: _resource_record_pre_action 16:38:38 INFO - Running main action method: download_and_extract 16:38:38 INFO - mkdir: /builds/slave/test/build/tests 16:38: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')]} 16:38:38 INFO - trying https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.test_packages.json 16:38:38 INFO - Downloading https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:38:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:38:38 INFO - Downloaded 986 bytes. 16:38:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:38:38 INFO - Using the following test package requirements: 16:38:38 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:38:38 INFO - u'common': [u'target.common.tests.zip'], 16:38:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:38:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:38:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:38:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:38:38 INFO - u'mozbase': [u'target.common.tests.zip'], 16:38:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:38:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:38:38 INFO - u'web-platform': [u'target.common.tests.zip', 16:38:38 INFO - u'target.web-platform.tests.zip'], 16:38:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:38:38 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:38:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.common.tests.zip 16:38:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:38:38 INFO - Fetch https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.common.tests.zip into memory 16:38:40 INFO - Content-Length response header: 62448330 16:38:40 INFO - Bytes received: 62448330 16:38:51 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:38:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.common.tests.zip 16:38:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:38:51 INFO - Fetch https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.common.tests.zip into memory 16:38:52 INFO - Content-Length response header: 62448330 16:38:52 INFO - Bytes received: 62448330 16:39:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.talos.tests.zip 16:39:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:39:07 INFO - Fetch https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.talos.tests.zip into memory 16:39:07 INFO - Content-Length response header: 14410013 16:39:07 INFO - Bytes received: 14410013 16:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:08 INFO - trying https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.tar.bz2 16:39:08 INFO - Downloading https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:39:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:39:09 INFO - Downloaded 63418812 bytes. 16:39:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.tar.bz2 16:39:09 INFO - mkdir: /builds/slave/test/properties 16:39:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:39:09 INFO - Writing to file /builds/slave/test/properties/build_url 16:39:09 INFO - Contents: 16:39:09 INFO - build_url:https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.tar.bz2 16:39:09 INFO - Running post-action listener: _resource_record_post_action 16:39:09 INFO - Running post-action listener: set_extra_try_arguments 16:39:09 INFO - [mozharness: 2017-05-15 23:39:09.268127Z] Finished download-and-extract step (success) 16:39:09 INFO - [mozharness: 2017-05-15 23:39:09.268224Z] Running populate-webroot step. 16:39:09 INFO - Running pre-action listener: _resource_record_pre_action 16:39:09 INFO - Running main action method: populate_webroot 16:39:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:39:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:39:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:39:09 INFO - '--tppagecycles', 16:39:09 INFO - '7'], 16:39:09 INFO - 'tests': ['tsvgm']}}, 16:39:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:39:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:39:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:39:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:39:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:39:09 INFO - 'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:39:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:39:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:39:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:39:09 INFO - 'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['damp', 'tps']}, 16:39:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:39:09 INFO - 'tests': ['damp', 'tps']}, 16:39:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['dromaeo_dom']}, 16:39:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:39:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:39:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:39:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['a11yr', 16:39:09 INFO - 'ts_paint', 16:39:09 INFO - 'tpaint', 16:39:09 INFO - 'sessionrestore', 16:39:09 INFO - 'sessionrestore_no_auto_restore', 16:39:09 INFO - 'tabpaint']}, 16:39:09 INFO - 'other-e10s': {'tests': ['a11yr', 16:39:09 INFO - 'ts_paint', 16:39:09 INFO - 'tpaint', 16:39:09 INFO - 'sessionrestore', 16:39:09 INFO - 'sessionrestore_no_auto_restore', 16:39:09 INFO - 'tabpaint']}, 16:39:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:39:09 INFO - 'ts_paint', 16:39:09 INFO - 'tpaint', 16:39:09 INFO - 'sessionrestore', 16:39:09 INFO - 'sessionrestore_no_auto_restore', 16:39:09 INFO - 'tabpaint']}, 16:39:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:39:09 INFO - 'ts_paint', 16:39:09 INFO - 'tpaint', 16:39:09 INFO - 'sessionrestore', 16:39:09 INFO - 'sessionrestore_no_auto_restore', 16:39:09 INFO - 'tabpaint']}, 16:39:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['a11yr', 16:39:09 INFO - 'ts_paint', 16:39:09 INFO - 'tpaint', 16:39:09 INFO - 'sessionrestore', 16:39:09 INFO - 'sessionrestore_no_auto_restore', 16:39:09 INFO - 'tabpaint']}, 16:39:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['a11yr', 16:39:09 INFO - 'ts_paint', 16:39:09 INFO - 'tpaint', 16:39:09 INFO - 'sessionrestore', 16:39:09 INFO - 'sessionrestore_no_auto_restore', 16:39:09 INFO - 'tabpaint']}, 16:39:09 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:39:09 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:39:09 INFO - 'bloom_basic_ref']}, 16:39:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['tsvgx', 16:39:09 INFO - 'tsvgr_opacity', 16:39:09 INFO - 'tart', 16:39:09 INFO - 'tscrollx', 16:39:09 INFO - 'cart', 16:39:09 INFO - 'tsvg_static']}, 16:39:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:39:09 INFO - 'tsvgr_opacity', 16:39:09 INFO - 'tart', 16:39:09 INFO - 'tscrollx', 16:39:09 INFO - 'cart', 16:39:09 INFO - 'tsvg_static']}, 16:39:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:39:09 INFO - 'talos_options': ['--disable-e10s'], 16:39:09 INFO - 'tests': ['tp5o']}, 16:39:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:39:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:39:09 INFO - 'talos_options': ['--disable-e10s', 16:39:09 INFO - '--xperf_path', 16:39:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:39:09 INFO - 'tests': ['tp5n']}, 16:39:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:39:09 INFO - 'talos_options': ['--xperf_path', 16:39:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:39:09 INFO - 'tests': ['tp5n']}}, 16:39:09 INFO - 'talos.zip': {'path': '', 16:39:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:39:09 INFO - Running post-action listener: _resource_record_post_action 16:39:09 INFO - [mozharness: 2017-05-15 23:39:09.281942Z] Finished populate-webroot step (success) 16:39:09 INFO - [mozharness: 2017-05-15 23:39:09.282032Z] Running create-virtualenv step. 16:39:09 INFO - Running pre-action listener: _resource_record_pre_action 16:39:09 INFO - Running main action method: create_virtualenv 16:39:09 INFO - Creating virtualenv /builds/slave/test/build/venv 16:39:09 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 16:39:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:39:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:39:09 INFO - Using real prefix '/usr' 16:39:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:39:09 INFO - Installing distribute.............................................................................................................................................................................................done. 16:39:11 INFO - Installing pip.................done. 16:39:11 INFO - Return code: 0 16:39:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:39:12 INFO - Reading from file tmpfile_stdout 16:39:12 INFO - Output received: 16:39:12 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) 16:39:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c45c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, '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': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:39:12 INFO - Using env: {'DISPLAY': ':0', 16:39:12 INFO - 'HOME': '/home/cltbld', 16:39:12 INFO - 'LANG': 'en_US.UTF-8', 16:39:12 INFO - 'LANGUAGE': 'en_US:en', 16:39:12 INFO - 'LOGNAME': 'cltbld', 16:39:12 INFO - 'MAIL': '/var/mail/cltbld', 16:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:12 INFO - 'MOZ_NO_REMOTE': '1', 16:39:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:12 INFO - 'NO_EM_RESTART': '1', 16:39:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:12 INFO - 'PWD': '/builds/slave/test', 16:39:12 INFO - 'SHELL': '/bin/bash', 16:39:12 INFO - 'SHLVL': '1', 16:39:12 INFO - 'TERM': 'linux', 16:39:12 INFO - 'TMOUT': '86400', 16:39:12 INFO - 'USER': 'cltbld', 16:39:12 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:12 INFO - '_': '/tools/buildbot/bin/python'} 16:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:12 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 16:39:12 INFO - Cleaning up... 16:39:12 INFO - Return code: 0 16:39:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c45c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, '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': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:39:12 INFO - Using env: {'DISPLAY': ':0', 16:39:12 INFO - 'HOME': '/home/cltbld', 16:39:12 INFO - 'LANG': 'en_US.UTF-8', 16:39:12 INFO - 'LANGUAGE': 'en_US:en', 16:39:12 INFO - 'LOGNAME': 'cltbld', 16:39:12 INFO - 'MAIL': '/var/mail/cltbld', 16:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:12 INFO - 'MOZ_NO_REMOTE': '1', 16:39:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:12 INFO - 'NO_EM_RESTART': '1', 16:39:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:12 INFO - 'PWD': '/builds/slave/test', 16:39:12 INFO - 'SHELL': '/bin/bash', 16:39:12 INFO - 'SHLVL': '1', 16:39:12 INFO - 'TERM': 'linux', 16:39:12 INFO - 'TMOUT': '86400', 16:39:12 INFO - 'USER': 'cltbld', 16:39:12 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:12 INFO - '_': '/tools/buildbot/bin/python'} 16:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:12 INFO - Downloading/unpacking psutil>=3.1.1 16:39: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 16:39: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 16:39: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 16:39: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 16:39:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:39:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:39:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:39:13 INFO - Installing collected packages: psutil 16:39:13 INFO - Running setup.py install for psutil 16:39:13 INFO - building 'psutil._psutil_linux' extension 16:39:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:39:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:39:13 INFO - building 'psutil._psutil_posix' extension 16:39:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:39:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:39:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:39:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:39:14 INFO - Successfully installed psutil 16:39:14 INFO - Cleaning up... 16:39:14 INFO - Return code: 0 16:39:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:14 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 0x23511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c45c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, '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': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:39:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:39:14 INFO - Using env: {'DISPLAY': ':0', 16:39:14 INFO - 'HOME': '/home/cltbld', 16:39:14 INFO - 'LANG': 'en_US.UTF-8', 16:39:14 INFO - 'LANGUAGE': 'en_US:en', 16:39:14 INFO - 'LOGNAME': 'cltbld', 16:39:14 INFO - 'MAIL': '/var/mail/cltbld', 16:39:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:14 INFO - 'MOZ_NO_REMOTE': '1', 16:39:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:14 INFO - 'NO_EM_RESTART': '1', 16:39:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:14 INFO - 'PWD': '/builds/slave/test', 16:39:14 INFO - 'SHELL': '/bin/bash', 16:39:14 INFO - 'SHLVL': '1', 16:39:14 INFO - 'TERM': 'linux', 16:39:14 INFO - 'TMOUT': '86400', 16:39:14 INFO - 'USER': 'cltbld', 16:39:14 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:14 INFO - '_': '/tools/buildbot/bin/python'} 16:39:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:39:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:39:15 INFO - Installing collected packages: mozsystemmonitor 16:39:15 INFO - Running setup.py install for mozsystemmonitor 16:39:15 INFO - Successfully installed mozsystemmonitor 16:39:15 INFO - Cleaning up... 16:39:15 INFO - Return code: 0 16:39:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c45c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, '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': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:39:15 INFO - Using env: {'DISPLAY': ':0', 16:39:15 INFO - 'HOME': '/home/cltbld', 16:39:15 INFO - 'LANG': 'en_US.UTF-8', 16:39:15 INFO - 'LANGUAGE': 'en_US:en', 16:39:15 INFO - 'LOGNAME': 'cltbld', 16:39:15 INFO - 'MAIL': '/var/mail/cltbld', 16:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:15 INFO - 'MOZ_NO_REMOTE': '1', 16:39:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:15 INFO - 'NO_EM_RESTART': '1', 16:39:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:15 INFO - 'PWD': '/builds/slave/test', 16:39:15 INFO - 'SHELL': '/bin/bash', 16:39:15 INFO - 'SHLVL': '1', 16:39:15 INFO - 'TERM': 'linux', 16:39:15 INFO - 'TMOUT': '86400', 16:39:15 INFO - 'USER': 'cltbld', 16:39:15 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:15 INFO - '_': '/tools/buildbot/bin/python'} 16:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:15 INFO - Downloading/unpacking jsonschema==2.5.1 16:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:39:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:17 INFO - Downloading functools32-3.2.3-2.tar.gz 16:39:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:39:17 INFO - warning: no files found matching '*.txt' 16:39:17 INFO - no previously-included directories found matching 'build' 16:39:17 INFO - no previously-included directories found matching 'dist' 16:39:17 INFO - no previously-included directories found matching '.git*' 16:39:17 INFO - Installing collected packages: jsonschema, functools32 16:39:17 INFO - Running setup.py install for functools32 16:39:17 INFO - warning: no files found matching '*.txt' 16:39:17 INFO - no previously-included directories found matching 'build' 16:39:17 INFO - no previously-included directories found matching 'dist' 16:39:17 INFO - no previously-included directories found matching '.git*' 16:39:17 INFO - Successfully installed jsonschema functools32 16:39:17 INFO - Cleaning up... 16:39:17 INFO - Return code: 0 16:39:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:17 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 0x23511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c45c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, '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': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:39:17 INFO - Using env: {'DISPLAY': ':0', 16:39:17 INFO - 'HOME': '/home/cltbld', 16:39:17 INFO - 'LANG': 'en_US.UTF-8', 16:39:17 INFO - 'LANGUAGE': 'en_US:en', 16:39:17 INFO - 'LOGNAME': 'cltbld', 16:39:17 INFO - 'MAIL': '/var/mail/cltbld', 16:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:17 INFO - 'MOZ_NO_REMOTE': '1', 16:39:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:17 INFO - 'NO_EM_RESTART': '1', 16:39:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:17 INFO - 'PWD': '/builds/slave/test', 16:39:17 INFO - 'SHELL': '/bin/bash', 16:39:17 INFO - 'SHLVL': '1', 16:39:17 INFO - 'TERM': 'linux', 16:39:17 INFO - 'TMOUT': '86400', 16:39:17 INFO - 'USER': 'cltbld', 16:39:17 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:17 INFO - '_': '/tools/buildbot/bin/python'} 16:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:39:17 INFO - Cleaning up... 16:39:17 INFO - Return code: 0 16:39:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:17 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 0x23511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c45c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, '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': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:39:17 INFO - Using env: {'DISPLAY': ':0', 16:39:17 INFO - 'HOME': '/home/cltbld', 16:39:17 INFO - 'LANG': 'en_US.UTF-8', 16:39:17 INFO - 'LANGUAGE': 'en_US:en', 16:39:17 INFO - 'LOGNAME': 'cltbld', 16:39:17 INFO - 'MAIL': '/var/mail/cltbld', 16:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:17 INFO - 'MOZ_NO_REMOTE': '1', 16:39:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:17 INFO - 'NO_EM_RESTART': '1', 16:39:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:17 INFO - 'PWD': '/builds/slave/test', 16:39:17 INFO - 'SHELL': '/bin/bash', 16:39:17 INFO - 'SHLVL': '1', 16:39:17 INFO - 'TERM': 'linux', 16:39:17 INFO - 'TMOUT': '86400', 16:39:17 INFO - 'USER': 'cltbld', 16:39:17 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:17 INFO - '_': '/tools/buildbot/bin/python'} 16:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:17 INFO - Downloading/unpacking blobuploader==1.2.4 16:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:18 INFO - Downloading blobuploader-1.2.4.tar.gz 16:39:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:39:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:39: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 16:39: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 16:39: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 16:39: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 16:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:39:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:19 INFO - Downloading docopt-0.6.1.tar.gz 16:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:39:19 INFO - Installing collected packages: blobuploader, requests, docopt 16:39:19 INFO - Running setup.py install for blobuploader 16:39:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:39:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:39:19 INFO - Running setup.py install for requests 16:39:19 INFO - Running setup.py install for docopt 16:39:20 INFO - Successfully installed blobuploader requests docopt 16:39:20 INFO - Cleaning up... 16:39:20 INFO - Return code: 0 16:39:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:39: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')]} 16:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39: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')]} 16:39:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:20 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 0x23511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c45c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, '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': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:39:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:39:20 INFO - Using env: {'DISPLAY': ':0', 16:39:20 INFO - 'HOME': '/home/cltbld', 16:39:20 INFO - 'LANG': 'en_US.UTF-8', 16:39:20 INFO - 'LANGUAGE': 'en_US:en', 16:39:20 INFO - 'LOGNAME': 'cltbld', 16:39:20 INFO - 'MAIL': '/var/mail/cltbld', 16:39:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:20 INFO - 'MOZ_NO_REMOTE': '1', 16:39:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:20 INFO - 'NO_EM_RESTART': '1', 16:39:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:20 INFO - 'PWD': '/builds/slave/test', 16:39:20 INFO - 'SHELL': '/bin/bash', 16:39:20 INFO - 'SHLVL': '1', 16:39:20 INFO - 'TERM': 'linux', 16:39:20 INFO - 'TMOUT': '86400', 16:39:20 INFO - 'USER': 'cltbld', 16:39:20 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:20 INFO - '_': '/tools/buildbot/bin/python'} 16:39:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:39:20 INFO - Running setup.py (path:/tmp/pip-GS5AXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:39:20 INFO - Running setup.py (path:/tmp/pip-lIn7et-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:39:20 INFO - Running setup.py (path:/tmp/pip-ObPGyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:39:20 INFO - Running setup.py (path:/tmp/pip-xD5SQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:39:20 INFO - Running setup.py (path:/tmp/pip-KdPGOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:39:20 INFO - Running setup.py (path:/tmp/pip-bXTd1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:39:20 INFO - Running setup.py (path:/tmp/pip-dIWlED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:39:20 INFO - Running setup.py (path:/tmp/pip-h5iFa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:39:20 INFO - Running setup.py (path:/tmp/pip-mB6uYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:39:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:39:20 INFO - Running setup.py (path:/tmp/pip-agZhIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:39:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:39:21 INFO - Running setup.py (path:/tmp/pip-c2U0Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:39:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:39:21 INFO - Running setup.py (path:/tmp/pip-TPc1qH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:39:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:39:21 INFO - Running setup.py (path:/tmp/pip-xzgiaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:39:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:39:21 INFO - Running setup.py (path:/tmp/pip-2OFBys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:39:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:21 INFO - Running setup.py (path:/tmp/pip-59L4tP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:39:21 INFO - Running setup.py (path:/tmp/pip-T5dIAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:39:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:39:21 INFO - Running setup.py (path:/tmp/pip-OVPY1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:39:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:39:21 INFO - Running setup.py install for manifestparser 16:39:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:39:21 INFO - Running setup.py install for mozcrash 16:39:21 INFO - Running setup.py install for mozdebug 16:39:21 INFO - Running setup.py install for mozdevice 16:39:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:39:21 INFO - Running setup.py install for mozfile 16:39:22 INFO - Running setup.py install for mozhttpd 16:39:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Running setup.py install for mozinfo 16:39:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Running setup.py install for mozInstall 16:39:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Running setup.py install for mozleak 16:39:22 INFO - Running setup.py install for mozlog 16:39:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Running setup.py install for moznetwork 16:39:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Running setup.py install for mozprocess 16:39:22 INFO - Running setup.py install for mozprofile 16:39:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Running setup.py install for mozrunner 16:39:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:39:22 INFO - Running setup.py install for mozscreenshot 16:39:23 INFO - Running setup.py install for moztest 16:39:23 INFO - Running setup.py install for mozversion 16:39:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:39:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:39:23 INFO - Cleaning up... 16:39:23 INFO - Return code: 0 16:39:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:23 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 0x23511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c45c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, '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': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:39:23 INFO - Using env: {'DISPLAY': ':0', 16:39:23 INFO - 'HOME': '/home/cltbld', 16:39:23 INFO - 'LANG': 'en_US.UTF-8', 16:39:23 INFO - 'LANGUAGE': 'en_US:en', 16:39:23 INFO - 'LOGNAME': 'cltbld', 16:39:23 INFO - 'MAIL': '/var/mail/cltbld', 16:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:23 INFO - 'MOZ_NO_REMOTE': '1', 16:39:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:23 INFO - 'NO_EM_RESTART': '1', 16:39:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:23 INFO - 'PWD': '/builds/slave/test', 16:39:23 INFO - 'SHELL': '/bin/bash', 16:39:23 INFO - 'SHLVL': '1', 16:39:23 INFO - 'TERM': 'linux', 16:39:23 INFO - 'TMOUT': '86400', 16:39:23 INFO - 'USER': 'cltbld', 16:39:23 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:23 INFO - '_': '/tools/buildbot/bin/python'} 16:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:39:23 INFO - Running setup.py (path:/tmp/pip-kB0LcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:39:23 INFO - Running setup.py (path:/tmp/pip-IU8I0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:39:23 INFO - Running setup.py (path:/tmp/pip-OTWkGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:39:23 INFO - Running setup.py (path:/tmp/pip-eTlpS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:39:23 INFO - Running setup.py (path:/tmp/pip-WnMn3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:39:23 INFO - Running setup.py (path:/tmp/pip-NSK_vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:39:23 INFO - Running setup.py (path:/tmp/pip-fjqPKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:39:24 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)) 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:39:24 INFO - Running setup.py (path:/tmp/pip-sE27Fs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:39:24 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)) 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:39:24 INFO - Running setup.py (path:/tmp/pip-WcHu97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:39:24 INFO - Running setup.py (path:/tmp/pip-yyjMy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:39:24 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)) 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:39:24 INFO - Running setup.py (path:/tmp/pip-tjk3HN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:39:24 INFO - Running setup.py (path:/tmp/pip-0Tavc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:39:24 INFO - Running setup.py (path:/tmp/pip-eqaIIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:39:24 INFO - Running setup.py (path:/tmp/pip-9aU1Y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:24 INFO - Running setup.py (path:/tmp/pip-1Sqq_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:39:24 INFO - Running setup.py (path:/tmp/pip-CUaGJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:39:24 INFO - Running setup.py (path:/tmp/pip-QHe70T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:39:24 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:39:24 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:39:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:25 INFO - Downloading blessings-1.6.tar.gz 16:39:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:39:25 INFO - Installing collected packages: blessings 16:39:25 INFO - Running setup.py install for blessings 16:39:26 INFO - Successfully installed blessings 16:39:26 INFO - Cleaning up... 16:39:26 INFO - Return code: 0 16:39:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:39:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:39:26 INFO - Reading from file tmpfile_stdout 16:39:26 INFO - Current package versions: 16:39:26 INFO - argparse == 1.2.1 16:39:26 INFO - blessings == 1.6 16:39:26 INFO - blobuploader == 1.2.4 16:39:26 INFO - docopt == 0.6.1 16:39:26 INFO - functools32 == 3.2.3-2 16:39:26 INFO - jsonschema == 2.5.1 16:39:26 INFO - manifestparser == 1.1 16:39:26 INFO - mozInstall == 1.12 16:39:26 INFO - mozcrash == 1.0 16:39:26 INFO - mozdebug == 0.1 16:39:26 INFO - mozdevice == 0.50 16:39:26 INFO - mozfile == 1.2 16:39:26 INFO - mozhttpd == 0.7 16:39:26 INFO - mozinfo == 0.9 16:39:26 INFO - mozleak == 0.1 16:39:26 INFO - mozlog == 3.4 16:39:26 INFO - moznetwork == 0.27 16:39:26 INFO - mozprocess == 0.25 16:39:26 INFO - mozprofile == 0.28 16:39:26 INFO - mozrunner == 6.13 16:39:26 INFO - mozscreenshot == 0.1 16:39:26 INFO - mozsystemmonitor == 0.3 16:39:26 INFO - moztest == 0.8 16:39:26 INFO - mozversion == 1.4 16:39:26 INFO - psutil == 3.1.1 16:39:26 INFO - requests == 1.2.3 16:39:26 INFO - wsgiref == 0.1.2 16:39:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:26 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 0x23511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c45c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, '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': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:39:26 INFO - Using env: {'DISPLAY': ':0', 16:39:26 INFO - 'HOME': '/home/cltbld', 16:39:26 INFO - 'LANG': 'en_US.UTF-8', 16:39:26 INFO - 'LANGUAGE': 'en_US:en', 16:39:26 INFO - 'LOGNAME': 'cltbld', 16:39:26 INFO - 'MAIL': '/var/mail/cltbld', 16:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:26 INFO - 'MOZ_NO_REMOTE': '1', 16:39:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:26 INFO - 'NO_EM_RESTART': '1', 16:39:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:26 INFO - 'PWD': '/builds/slave/test', 16:39:26 INFO - 'SHELL': '/bin/bash', 16:39:26 INFO - 'SHLVL': '1', 16:39:26 INFO - 'TERM': 'linux', 16:39:26 INFO - 'TMOUT': '86400', 16:39:26 INFO - 'USER': 'cltbld', 16:39:26 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:26 INFO - '_': '/tools/buildbot/bin/python'} 16:39:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:39:26 INFO - Cleaning up... 16:39:26 INFO - Return code: 0 16:39:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c45c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, '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': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:39:26 INFO - Using env: {'DISPLAY': ':0', 16:39:26 INFO - 'HOME': '/home/cltbld', 16:39:26 INFO - 'LANG': 'en_US.UTF-8', 16:39:26 INFO - 'LANGUAGE': 'en_US:en', 16:39:26 INFO - 'LOGNAME': 'cltbld', 16:39:26 INFO - 'MAIL': '/var/mail/cltbld', 16:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:26 INFO - 'MOZ_NO_REMOTE': '1', 16:39:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:26 INFO - 'NO_EM_RESTART': '1', 16:39:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:26 INFO - 'PWD': '/builds/slave/test', 16:39:26 INFO - 'SHELL': '/bin/bash', 16:39:26 INFO - 'SHLVL': '1', 16:39:26 INFO - 'TERM': 'linux', 16:39:26 INFO - 'TMOUT': '86400', 16:39:26 INFO - 'USER': 'cltbld', 16:39:26 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:26 INFO - '_': '/tools/buildbot/bin/python'} 16:39:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:39:26 INFO - Cleaning up... 16:39:26 INFO - Return code: 0 16:39:26 INFO - Running post-action listener: _resource_record_post_action 16:39:26 INFO - Running post-action listener: _start_resource_monitoring 16:39:26 INFO - Starting resource monitoring. 16:39:26 INFO - [mozharness: 2017-05-15 23:39:26.768501Z] Finished create-virtualenv step (success) 16:39:26 INFO - [mozharness: 2017-05-15 23:39:26.769002Z] Running install step. 16:39:26 INFO - Running pre-action listener: _resource_record_pre_action 16:39:26 INFO - Running main action method: install 16:39:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:39:26 INFO - Reading from file tmpfile_stdout 16:39:26 INFO - Detecting whether we're running mozinstall >=1.0... 16:39:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:39:27 INFO - Reading from file tmpfile_stdout 16:39:27 INFO - Output received: 16:39:27 INFO - Usage: mozinstall [options] installer 16:39:27 INFO - Options: 16:39:27 INFO - -h, --help show this help message and exit 16:39:27 INFO - -d DEST, --destination=DEST 16:39:27 INFO - Directory to install application into. [default: 16:39:27 INFO - "/builds/slave/test"] 16:39:27 INFO - --app=APP Application being installed. [default: firefox] 16:39:27 INFO - mkdir: /builds/slave/test/build/application 16:39:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:39:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:39:42 INFO - Reading from file tmpfile_stdout 16:39:42 INFO - Output received: 16:39:42 INFO - /builds/slave/test/build/application/firefox/firefox 16:39:42 INFO - Running post-action listener: _resource_record_post_action 16:39:42 INFO - [mozharness: 2017-05-15 23:39:42.931608Z] Finished install step (success) 16:39:42 INFO - [mozharness: 2017-05-15 23:39:42.931891Z] Running run-tests step. 16:39:42 INFO - Running pre-action listener: _resource_record_pre_action 16:39:42 INFO - Running main action method: run_tests 16:39:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:39:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:39:42 INFO - Python 2.7.3 16:39:42 INFO - Return code: 0 16:39:42 INFO - grabbing minidump binary from tooltool 16:39:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:42 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 0x26c17a0>, '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 0x26c26a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:39:42 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 16:39:42 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 16:39:42 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 16:39:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:39:43 INFO - Return code: 0 16:39:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:39:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:39:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:39:43 INFO - ENV: RUST_BACKTRACE is now 1 16:39:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:39:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:39:43 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/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:39:43 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/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:39:43 INFO - Using env: {'DISPLAY': ':0', 16:39:43 INFO - 'HOME': '/home/cltbld', 16:39:43 INFO - 'LANG': 'en_US.UTF-8', 16:39:43 INFO - 'LANGUAGE': 'en_US:en', 16:39:43 INFO - 'LOGNAME': 'cltbld', 16:39:43 INFO - 'MAIL': '/var/mail/cltbld', 16:39:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:39:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:43 INFO - 'MOZ_NO_REMOTE': '1', 16:39:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:39:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:43 INFO - 'NO_EM_RESTART': '1', 16:39:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:43 INFO - 'PWD': '/builds/slave/test', 16:39:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:39:43 INFO - 'RUST_BACKTRACE': '1', 16:39:43 INFO - 'SHELL': '/bin/bash', 16:39:43 INFO - 'SHLVL': '1', 16:39:43 INFO - 'TERM': 'linux', 16:39:43 INFO - 'TMOUT': '86400', 16:39:43 INFO - 'USER': 'cltbld', 16:39:43 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1494891140.6873-252914416', 16:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:43 INFO - '_': '/tools/buildbot/bin/python'} 16:39:43 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/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:39:43 INFO - mozversion application_buildid: 20170515230435 16:39:43 INFO - mozversion application_changeset: a4235c4be96edaf90b5d6d7c20272a8761ca2339 16:39:43 INFO - mozversion application_display_name: Nightly 16:39:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:39:43 INFO - mozversion application_name: Firefox 16:39:43 INFO - mozversion application_remotingname: firefox 16:39:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:39:43 INFO - mozversion application_vendor: Mozilla 16:39:43 INFO - mozversion application_version: 55.0a1 16:39:43 INFO - mozversion platform_buildid: 20170515230435 16:39:43 INFO - mozversion platform_changeset: a4235c4be96edaf90b5d6d7c20272a8761ca2339 16:39:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:39:43 INFO - mozversion platform_version: 55.0a1 16:39:43 INFO - using testdate: 1494891583 16:39:43 INFO - actual date: 1494891583 16:39:43 INFO - starting webserver on 'localhost:49503' 16:39:43 INFO - SUITE-START | Running 2 tests 16:39:43 INFO - TEST-START | dromaeo_css 16:39:43 INFO - operating with platform_type : linux_ 16:39:43 INFO - Initialising browser for dromaeo_css test... 16:39:43 INFO - TEST-INFO | started process 4757 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpovhbt7/profile http://localhost:49503/getInfo.html) 16:39:43 INFO - PID 4757 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 16:39:47 INFO - PID 4757 | __metrics Screen width/height:1600/1200 16:39:47 INFO - PID 4757 | colorDepth:24 16:39:47 INFO - PID 4757 | Browser inner width/height: 1024/695 16:39:47 INFO - PID 4757 | __metrics 16:39:49 INFO - TEST-INFO | 4757: exit 0 16:39:49 INFO - Browser initialized. 16:39:49 INFO - Running cycle 1/1 for dromaeo_css test... 16:39:49 INFO - TEST-INFO | started process 4929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpovhbt7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:40:01 INFO - PID 4929 | RSS: Main: 162521088 16:40:01 INFO - PID 4929 | 16:43:06 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:49503/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49503/tests/dromaeo/cssquery-ext.html) 16:43:09 INFO - PID 4929 | RSS: Main: 168210432 16:43:09 INFO - PID 4929 | 16:46:11 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:49503/tests/dromaeo/cssquery-ext.html (next: http://localhost:49503/tests/dromaeo/cssquery-jquery.html) 16:46:11 INFO - PID 4929 | RSS: Main: 167170048 16:46:11 INFO - PID 4929 | 16:49:13 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:49503/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49503/tests/dromaeo/cssquery-mootools.html) 16:49:14 INFO - PID 4929 | RSS: Main: 169189376 16:49:14 INFO - PID 4929 | 16:52:15 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:49503/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49503/tests/dromaeo/cssquery-prototype.html) 16:52:16 INFO - PID 4929 | RSS: Main: 166608896 16:52:16 INFO - PID 4929 | 16:55:19 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:49503/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49503/tests/dromaeo/cssquery-yui.html) 16:55:20 INFO - PID 4929 | RSS: Main: 167190528 16:55:20 INFO - PID 4929 | 16:58:22 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:49503/tests/dromaeo/cssquery-yui.html (next: http://localhost:49503/tests/dromaeo/cssquery-dojo.html) 16:58:22 INFO - PID 4929 | RSS: Main: 173273088 16:58:22 INFO - PID 4929 | 16:58:22 INFO - PID 4929 | __start_tp_report 16:58:22 INFO - PID 4929 | _x_x_mozilla_page_load 16:58:22 INFO - PID 4929 | _x_x_mozilla_page_load_details 16:58:22 INFO - PID 4929 | |i|pagename|runs| 16:58:22 INFO - PID 4929 | |0;dojo.html;2744.245332533553;2749.5188342040137;2779.902703405381;2793.818401803883;2795.035712679125;5259.237410575464;5368.570514358852;5376.838694839152;5399.244105825188;5407.540359069479;5322.920156197651;5355.169948657955;5446.128619420893;5468.125099984003;5469.507744303012;4797.2324428091515;4805.855824325258;4813.3983252093485;4869.902601947959;4902.044101400228;4792.6405519586;4798.160321943664;4901.901961960759;4915.803367865299;4950.381202349707;4714.269288260325;4819.349387832633;4821.4696383397795;4902.142125128106;4910.680805747633;4765.046990601893;4771.928421073687;4812.181929072066;4823.03539292143;4877.365942427462;4692.7653617319;4768.809247630091;4785.880352991168;4791.185498465269;4847.51524847516;5347.491988261109;5351.812686555952;5374.758135883855;5385.219143224211;5399.460053994609;5620.325560932714;5642.407547207526;5662.462066103713;5667.773289068474;5746.224259724925;5708.029634962067;5731.168980497846;5739.3399759027825;5760.654360738369;5763.106718458645;5290.576753859682;5314.12316967696;5321.095413779667;5331.760070796823;5368.2752828367675;5885.61743486672;5900.881982360329;5904;5904.911426328608;5908.99547076998;5644.8024319148635;5648.068068768689;5664.915026274609;5666.9716651416475;5667.631603945731;5745.741441635137;5758.884822303614;5762.30852297727;5764.13537969308;5765.336986246635;5776.942230577641;5779.104238842986;5796.710164491758;5802.506786923159;5837.6497410155525;5789.8263052108505;5791.652500849962;5793.768249269982;5799.94200057994;5801.506871915934;4375.693701440932;4418.80115394808;4428.645708343325;4484.417025786627;4517.909641807146;3921.8627348042683;3938.074552480165;3940.4680368149934;3964.4647972523917;3998.420229066769;4990.351254336913;4997.700137991732;5003.899922001539;5005.724685142352;5013.771625951666;5678.716064196774;5747.971260143672;5851.707414629252;5867.237259156283;5928.110783382527;4925.285833554186;4927.211646136601;4930.9506904930495;4934.481879402683;4966.950330496721;5010.549050585465;5127.512886275872;5127.564157046693;5129.461406552333;5130.204818253177;5325.254464375068;5327.147656374961;5332.0402327581405;5332.386775520709;5335.466453354788;5701.4298570142655;5859.9121013183985;5911.290645122441;5921.5558833086825;5947.791827286024;5385.4076051635075;5467;5468.48049435303;5468.6171967963455;5470.753816078366;5570.053090974625;5653.180288858175;5723.3418156912485;5777.45114214149;5780.323706125177;5702.0306547887785;5759.39526349744;5885.146653735099;5894.793682221101;5898.1152827076285;5569.55443564522;5573.442655734394;5635.211070450058;5750.9648263313;5751.309842818889;5645.604807663424;5669.773209071591;5713.371529131709;5714.428557144419;5725.055365864586;5639.2668953035845;5644.209810626434;5645.350784659652;5647.887042259214;5649.37856835756;5708.942910570841;5735.225744524436;5737.340205876378;5751.396103409082;5754.482096611325;5510.366307874486;5540.916886246629;5545.667259964415;5564.304461942257;5577.637453565505 16:58:22 INFO - PID 4929 | |1;ext.html;735.4631119282923;758.6434375843353;1013.1084645511949;1029.1201023125222;1148.2019996102701;33499.83250083749;34359.65640343596;34458.483122753176;34581.96254112377;34583.13542161441;31973.84013079937;32298.838505807496;32346.514802277925;32496;32510.67489325106;6409.8397540061405;6483.7082331295205;6606.801795946105;6609.3390660933765;7129.287071292855;6981.755638552626;7080.398166155883;7082.220955694869;7086.610236436994;7092.5389849659605;7049.118860142482;7051.612161331124;7119.074520312352;7120.786376408716;7126.073610430637;7037.401820845209;7056.329648683367;7086.929130708679;7093.219745828006;7095.325944035309;7010.964945175241;7018.5788852669;7039.859202815915;7052.2595127511895;7074.398676112485;32676.6732332676;32849.50725739116;32894.34211315784;32953.67046329554;33096.50355244649;2918.649762028549;2923.8830446782104;2946.1603443018853;2958.4822656035108;2973.2864112612815;25115.120970765936;25212.24363269105;25301.240962771146;25410;25446.491070178494;3086.459869522847;3111.9377612447624;3127.890523831678;3142.9842850785826;3150.9054728358187;146192;146712.99999999892;147407;147662;147663.99999999892;37788.24423511514;39387;39755.20489590192;40762.184756304705;41171.58828411737;41983.70244463355;42061.78969105196;42110.57889421067;42148.367774483406;42238.788806055774;12439.689007774878;12624.558140465224;12630.052746044083;12637.87362126367;12655.17741346828;10067.59729610822;10125.341852779546;10127.898721012843;10133.391996480235;10135.594576216869;12769.553065642658;12809.295488748208;12811.551595694105;12864.356782160854;13056.498502672124;5213.452587478336;5266.104762190437;5278.81524146653;5290.761915713725;5350.3312085989255;867.9696210632598;905.9943462756336;915.514777168102;919.7102912582515;939.6476321379482;20344;20643.58712825735;21126;21373;21573.67639485409;934.4907025671025;993.3493561717087;999.8000399920045;1000.3397757480028;1003.5484032185545;10625.574977000988;11298.378589177673;11315.490802913888;11581.88418115808;11660.416979151008;176607.11696441437;177325;178004;178921;179643;41434.1713165735;41535.7923210382;41666.37500437496;41701.1659766803;41729.582704173175;1199.244475980127;1243.5585367194515;1245.0724210463263;1248.5754843353304;1262.4319056424645;19423.8057619422;19642.410727678194;20006;20477.79522204759;20875.164993400092;29992.85003574968;30109;30301;30338.393232135677;30372.84813575918;155466;156591;156725;156849;156963;29305.706942930297;29506.852465737535;29655.258618533848;31251.84374078115;31395.529067063555;37416;37511.43732843955;37750.811245943594;37899.242015160096;38121.428178577895;27044.864775675996;27970.720292797625;28389.148325550268;28504.287392815346;28603.85698071496;27518.587221192098;27802.721972780822;28472;29502.55746163766;29914;31016.844915775277;31260.531092033183;31285.217869553442;31300.37399252048;31336;26189.73810261873;27322.863385682944;27427;27473.86263068752;27767.861160694876;20552.28067017707;22545;22624.208152714575;22668.659970100132;22737.88631056834 16:58:22 INFO - PID 4929 | |2;jquery.html;414.60405312926156;418.58141858141875;426.78873957391096;431.35297054418373;445.50326386079513;14911.552653420387;16929.915350423245;17759.556011099714;17787.199576019077;18057.729134062967;18589.907050464764;18721.15754791034;18931.810681893177;19450.805491945077;19630.803691963076;5474.698891560963;5534.944650553493;5576.665400075988;5615.326160860703;5625.043742563754;5497.835064948058;5577.3028371453565;5584.832455026335;5597.888042239153;5612.214289999403;5286.074936886049;5455.372632147294;5508.586855985797;5514.80698175564;5551.944480555183;5504.4770746778995;5531.059719847635;5551.72241387931;5551.77792888284;5567.3040869891265;4904.485029071968;4946.109700253953;4967.3542439482635;4974.204127339608;4987;21594;21624.567508649896;22269.991401547715;22298;22474;1616.9029858208546;1629.030726717601;1645.6050547868545;1647.5222185566172;1658.9834101659071;14614.123152610771;14654.487092951695;15107.169105699182;15133.470328538559;15287.847121528754;1984.5534754680225;2031.3296612118113;2057.6399130152313;2067.4831292176955;2072.1504183284933;87398;87421;88873;89149;91581;10973.561057557768;11035.613753518588;11163.330200188015;11288.943555282336;11407.08743300534;15526;15796;15815.604609884615;15820.60448488797;15908.363665453253;7559.130699969464;7566.508176968479;7616.124145723313;7816;7856.45004849655;6831.28271531492;6955.269696681877;7172.713091476387;7174.820629484305;7219.241979592172;6937.68780404883;7016.649167541603;7091.404434002308;7144.178419481722;7159.89260161098;2104.1583366653463;2113.0702490903955;2115.978840211609;2122.373899699592;2256.6953461282847;844.472204871955;889.6174644902754;890.8663700444985;905.5789058088021;918.8989211186764;17137.571560710832;17231.56921076983;17556.29774808993;17985.65107616934;18333.083345832656;879.9868001979847;885.0927799006071;892.9642814287486;897.4704924094815;906.4742449379344;9355.38278825133;9616.51917404141;9899.65351212704;9945.303828732063;9994.750131246778;377015;379113;381360;383836;388262.52587164054;76836;77616;78241.60879195567;78956.60521697355;80477.59761201157;1027.3219675014454;1029.25893356783;1057.799018186542;1063.3035361838008;1066.0831684751108;14333.498327558485;14487.420503180176;14759.778603320745;15020.474283400466;15256.542303730906;39292.60707392889;40028.599714002485;40521;41338.58661413348;43865.78067109772;51090.744546277034;52317.215241772166;53010;54069.45930540644;54821;10239.48802559854;10640.41477718702;10652.786944261226;10719.356838589709;10948.890511094787;11306.660800176009;11538.711532211762;11807.291562506278;11952.462139203582;12029.218100823418;10261.384316940708;10347.327423717434;10427.739306517398;10431.791364172826;10625.149988000787;9959.502024898871;10061.094501494608;10077.445740484343;10317.793644127225;10649.094826939487;10941.835872461761;11100.167487438313;11248.156388270749;11453.141014424118;11568.305901645928;11342.546298148312;11627.534898604299;11733.237339572901;11972.102092343282;11988.280703157838;10900.291481053706;10980.231383803057;11273.830892536454;11313.7737245253;11466.356200370145 16:58:22 INFO - PID 4929 | |3;mootools.html;1227.324971265804;1271.0721173543313;1286.5304163980145;1291.696451333937;1303.042263936007;10017.699469015932;10492.632757853477;10493.265471416995;10542.314749541274;10743.1405487561;10550.314229575073;10602.151827853773;10750.301230420017;10752.946235268832;10757.408342541177;2231.129859354853;2262.6040442922504;2267.5918334699745;2267.5918334699745;2271.114265436481;2204.9559008819815;2257.7516473187934;2259.1076524772707;2264.1283106004144;2271.4207876991345;2214.402111429913;2225.388018294974;2267.9433014174697;2269.4326418395403;2270.1713874435877;2138.4012476506605;2172.3048624440107;2208.5141268920893;2222.1777942161502;2225.9109635614477;1837.9724304135448;1920.875143115693;2141.8286537076997;2225.121077174523;2243.876586787726;10286.794264114751;10338.638147664795;10342.896571034269;10386.844197337046;10392;5605.775768969236;5765.279340082489;5795.449432303966;5801.970990145064;5813.4477224664015;3193.4483186499565;3402.2004828865197;3483.1466290758926;3486.6513348665185;3495.2310491691787;1044.2272718188563;1062.1131355318319;1084.6529110684585;1085.7459634122602;1091.154355374583;15384.692306153926;15523.91332606718;15543.611409714735;15546.999999999887;15559.766603500844;4668.323093151474;4698.788554515055;4941.998490611309;5006.4492905780335;5022.020705962303;10923.508442120125;10936.507857146447;10946.39794811293;10955.83566246507;10983.066439352584;5698.202251684767;5698.430156984268;5708.828735137952;5710.914336284959;5712.0860662293835;5038.143515602356;5163.225516172529;5218.530332270114;5242.370915490165;5294.152935530296;3783.375743002429;3853.2486165197515;3883.5339759229073;3934.724569280123;3977.443157957888;1115.2360632966445;1137.181229514748;1137.6643890052455;1151.1366475143643;1151.9769604607861;717.1859938969308;734.5886303669962;743.0674503498076;750.579675381788;756.8940348351125;5595.073470159286;5639.802606908738;5796.478316951495;5847.181394604759;5879.323877754113;535.253321616343;555.0370107862855;559.5132234955614;559.5495626021011;563.352145033215;3247.7888937219004;3315.966840331566;3363.1423986883187;3402.809016844085;3435.055359776082;12973.416196271002;13258.337083145803;13770.24263665508;13784.931075344559;13816.37826297819;13122.212667239995;13328.200307981551;13454.192748434929;13508.86491135076;13554.932225339007;7421.628918554051;7502.137254215726;7654.349380302625;7667.348275396654;7709.190534993857;5522.088855338824;5524.011355911788;5558.694271815089;6695.832604184934;6707.5304728668525;11924.465731394755;11939.164258501822;11948.192174838732;12081.697957551132;12367.69080772952;12003.459844307201;12209.938950305192;12249.510019599473;12465.875341246834;12572.811407828707;8372.66509339644;8434.367422443218;8466.365022623204;8521.957390213258;8524.74425767228;9540.666076687283;9631.229501639711;9632.518374081128;9637.18083962871;9658.90341096608;8032.277095061472;8033.758987230392;8101.635426405944;8176.550289734122;8199.549024803693;7935.5635440049045;7983.960080199562;8020.197980201933;8086.1509541499645;8100.878486822585;7582.696692132253;7599.924000759922;7620.047494063242;7685.116211635734;7688.884666730115;5405.891882162414;5450.31871016123;5513.255710479034;5517.227588137584;5552.555795536428;5151.562117219928;5215.608829337891;5390.811321603882;5395.136778115482;5487.64330318528 16:58:22 INFO - PID 4929 | |4;prototype.html;371.46323562452255;372.2907860525698;379.85755341746847;380.9923801523969;384.6499685286388;5461.590380721452;5509.504144626982;5524.447555244474;5525.2817133772605;5625.7497001199545;5297.576193904488;5456.536194423479;5459.727013649322;5476.3702174249875;5481.5340696040885;4039.131586708857;4145.336746120622;4148.937765933513;4155.833766649345;4167.249895018896;4125.855595054174;4141.233871733723;4146.880342307575;4147.149834283965;4154.231467178582;4117.197146556423;4117.938230926538;4125.21620892031;4132.2768515509815;4133.772642504661;4063.773675377604;4106.096658735073;4122.732022418533;4123.463949686537;4140.254754144253;3572.99956012317;3583.175869549992;3584.8028358440274;3591.3894637911358;3599.8020108894;5327.946720532784;5369.812056578001;5400.162974738922;5402.108652072403;5403.595400046981;4755.524447555252;4806.086843499724;4847.51524847516;4856.441509226449;4869.172240719086;5722.599418040739;5771.50942169916;5777.740001699892;5781.248437703071;5798.7390567424145;4002.899927501836;4008.5590585035625;4016.49793775778;4018.1963607278367;4026.2350153470748;6051.304100028509;6167.198264225667;6194.163787888668;6240.785995212424;6246.750129994794;4102.2410853992105;4115.5678653741525;4125.958740412558;4128.979355103205;4136.772477513706;4176.2900306947895;4182.2890108681595;4183.142455796555;4183.267928112568;4194.433751443577;4654.953450465519;4834.540718631724;4838.491958344323;4840.273958906192;4841.491643377465;5860.326062502867;5874.118882167623;5878.413498060925;5889.23439952812;5909.793157239476;1409.4714482069223;1444;1448.8840892728767;1450.804141440913;1455.3814628782725;907.5054095517959;954.0936110694868;959.7984423271051;975.2100798353357;975.2831668723483;5095.7578665387855;5132.717700526432;5138.152204886227;5139.486051394905;5142.611494896431;6073.240844894388;6079.513638908877;6109.2974307955155;6126.172966649535;6141.846453838601;4926.989967056745;5092.796288148506;5115.465360391942;5116.590672746172;5149.485051494896;3186.9362612747614;3208.6363295599276;3211.5985501812274;3222.1300248932657;3230.1924518870283;5475.780968761284;5615.438456154352;5621.409751976065;5625.803096891589;5652;6039.577229593974;6232.407921247474;6290.591111577733;6309.526785491106;6320.462760665395;5623.268975033301;5630.18362337467;5633.126865335879;5650.463205995506;5678.8828099627435;5979.701014949323;6097.390260973867;6119.44924956756;6120.234970628671;6127.20346354971;5819.76720931158;5984.713286643218;6032.607880487754;6038.818835434944;6040.154378386941;5648.265725455664;5655.614374478279;5668.829935101953;5672.461116193955;5684.602077854676;4041.8989525262105;4122.618922660822;4145.647619952218;4156.147989662051;4163.12574359388;6096.939030609815;6128.754849806136;6130.785422510191;6140.907886381798;6145.477634401126;5870.735816888165;5894.263217097863;5913.876363490923;5916.319623243382;5928.407159284037;5739.044392138136;5848.561357898089;5854.970725146347;5862.853428664147;5911.7635294587735;5772.307323121085;5796.913046304393;5804;5830.5044071254415;5848.327442344014;5836.533077353716;5871.823845284648;5917.763289468373;5923.318818335774;5954.166416701752;5763.625364351304;5800.419958004166;5807.303123625192;5809.390014048464;5835.270591176103 16:58:22 INFO - PID 4929 | |5;yui.html;658.3613894522312;669.2604671837616;674.8279188806854;679.6228093408159;680.4794332335764;11686;12079.818802717955;12084.69788255293;14536.273186340693;15738.055716657009;14776.261186940663;14922.552323430287;15001.399944002227;15091.471798487055;15912.443064492745;845.2308399356572;862.3790870573177;865.0959747064302;865.6277800545763;870.0429527519741;860.6901515454431;894.8478758611019;915.8031023329983;918.8897332320131;921.8202450522151;949.8432758594823;950.116391755667;950.5627411390768;953.9952300238524;954.7231302922179;936.3773090894547;942.8444306689422;944.9196818270456;945.1965829045291;947.8957314695378;697.2365260040248;699.3845416033857;700.037448508301;701.551007355293;702.9894551581731;12657.30384828834;12772.552960646426;12837.743245135138;12890.54883079097;12895.548655797002;15789.68420631581;15852.76220856688;16038.679226415523;16163.515094547183;16202.35190592375;11350.375729334883;11607.303561786235;11703.882961170364;11723.7069073273;11785.705357366049;2781.123945957017;2818;2831.6177316062276;2832.660080790298;2839.4463079699467;69837.65081174612;71139.64430177868;71162;72064;73195.26804731938;12252.509899603916;12258.938705306417;12337.876621233672;12369.876301236873;12425.875741242653;20585.89707051455;22030.228941986952;22034.11863525473;22178.223762168247;22362.993665285103;5822.883542329129;6030.939690603125;6081.969590152021;6091.66495842724;6262.718177682015;2946.7495262902466;2991.3269514359095;2992.8054676445963;2995.9700402996127;2998.625171853518;3227.2415982244156;3285.6221534523356;3324.7672662913847;3325.48454989477;3357.697807197364;705.5237714542664;718.2207305074053;722.227216877946;722.9710811567584;724.4023680463533;588.7497813429276;593.8277899409123;597.5189972072524;602.6625089949642;619.7304172684896;9242.445453272825;9957.352772069793;10144.797104058025;10293.176545876311;10420.791584168273;539.9352077750616;544.1212441906292;549.3490214096308;553.0818840724459;555.8804856955774;4409.140217657526;4423.8230470781455;4454.688171827941;4572.497025327211;4585.79363928624;63808;64407.677961609894;64499.35500645027;66318;67515;57390.7130464345;58534.707326463096;59803.40196598065;60225.397746022856;63306;51651;51770.48229517657;52119.73940130275;52519.47480525222;52708.36458177076;8794.42836215644;8811.955940220258;8836.381453298207;8844.601992910206;9045.728628141167;26607.866960665073;27073.1878043659;27483.862580687768;27632.58551121775;28089.859550702116;32722.50916236211;32807.17982050468;32991.50512742359;33164.50253246252;34140.14649633779;10797.838032429363;13462.528811491551;13748.381322840296;13819.723605527632;13939.233342166279;14894.851051489346;15768.842311576738;15933.80496462793;16668;16986.065766382966;14024.158550487004;15202.847971520143;15240.314185861442;15621.14083725406;15640.843591563938;13816.723665526431;13950;14689.412423503367;15185.6203594911;15341.693166136392;13393.397297121848;13396.531121410704;13591.932040339734;13830.723385532032;13917.37371818291;10182;10199.847002294822;10416.479176041015;10566.154707623526;10624.52189651483;11573.479193436484;12269.509219631473;12302.323372214614;12304.938475307566;12313.583937847263 16:58:22 INFO - PID 4929 | __end_tp_report 16:58:22 INFO - PID 4929 | __start_cc_report 16:58:22 INFO - PID 4929 | _x_x_mozilla_cycle_collect,284 16:58:22 INFO - PID 4929 | __end_cc_report 16:58:22 INFO - PID 4929 | __startTimestamp1494892702263__endTimestamp 16:58:22 INFO - PID 4929 | 16:58:22 INFO - PID 4929 | ------- Summary: start ------- 16:58:22 INFO - PID 4929 | Number of tests: 6 16:58:22 INFO - PID 4929 | 16:58:22 INFO - PID 4929 | [#0] dojo.html Cycles:165 Average:5285.21 Median:5468.62 stddev:644.75 (11.8%) stddev-sans-first:615.14 16:58:22 INFO - PID 4929 | Values: 2744.2 2749.5 2779.9 2793.8 2795.0 5259.2 5368.6 5376.8 5399.2 5407.5 5322.9 5355.2 5446.1 5468.1 5469.5 4797.2 4805.9 4813.4 4869.9 4902.0 4792.6 4798.2 4901.9 4915.8 4950.4 4714.3 4819.3 4821.5 4902.1 4910.7 4765.0 4771.9 4812.2 4823.0 4877.4 4692.8 4768.8 4785.9 4791.2 4847.5 5347.5 5351.8 5374.8 5385.2 5399.5 5620.3 5642.4 5662.5 5667.8 5746.2 5708.0 5731.2 5739.3 5760.7 5763.1 5290.6 5314.1 5321.1 5331.8 5368.3 5885.6 5900.9 5904.0 5904.9 5909.0 5644.8 5648.1 5664.9 5667.0 5667.6 5745.7 5758.9 5762.3 5764.1 5765.3 5776.9 5779.1 5796.7 5802.5 5837.6 5789.8 5791.7 5793.8 5799.9 5801.5 4375.7 4418.8 4428.6 4484.4 4517.9 3921.9 3938.1 3940.5 3964.5 3998.4 4990.4 4997.7 5003.9 5005.7 5013.8 5678.7 5748.0 5851.7 5867.2 5928.1 4925.3 4927.2 4931.0 4934.5 4967.0 5010.5 5127.5 5127.6 5129.5 5130.2 5325.3 5327.1 5332.0 5332.4 5335.5 5701.4 5859.9 5911.3 5921.6 5947.8 5385.4 5467.0 5468.5 5468.6 5470.8 5570.1 5653.2 5723.3 5777.5 5780.3 5702.0 5759.4 5885.1 5894.8 5898.1 5569.6 5573.4 5635.2 5751.0 5751.3 5645.6 5669.8 5713.4 5714.4 5725.1 5639.3 5644.2 5645.4 5647.9 5649.4 5708.9 5735.2 5737.3 5751.4 5754.5 5510.4 5540.9 5545.7 5564.3 5577.6 16:58:22 INFO - PID 4929 | 16:58:22 INFO - PID 4929 | [#1] ext.html Cycles:180 Average:30666.58 Median:30319.70 stddev:41552.34 (137.0%) stddev-sans-first:41608.12 16:58:22 INFO - PID 4929 | Values: 735.5 758.6 1013.1 1029.1 1148.2 33499.8 34359.7 34458.5 34582.0 34583.1 31973.8 32298.8 32346.5 32496.0 32510.7 6409.8 6483.7 6606.8 6609.3 7129.3 6981.8 7080.4 7082.2 7086.6 7092.5 7049.1 7051.6 7119.1 7120.8 7126.1 7037.4 7056.3 7086.9 7093.2 7095.3 7011.0 7018.6 7039.9 7052.3 7074.4 32676.7 32849.5 32894.3 32953.7 33096.5 2918.6 2923.9 2946.2 2958.5 2973.3 25115.1 25212.2 25301.2 25410.0 25446.5 3086.5 3111.9 3127.9 3143.0 3150.9 146192.0 146713.0 147407.0 147662.0 147664.0 37788.2 39387.0 39755.2 40762.2 41171.6 41983.7 42061.8 42110.6 42148.4 42238.8 12439.7 12624.6 12630.1 12637.9 12655.2 10067.6 10125.3 10127.9 10133.4 10135.6 12769.6 12809.3 12811.6 12864.4 13056.5 5213.5 5266.1 5278.8 5290.8 5350.3 868.0 906.0 915.5 919.7 939.6 20344.0 20643.6 21126.0 21373.0 21573.7 934.5 993.3 999.8 1000.3 1003.5 10625.6 11298.4 11315.5 11581.9 11660.4 176607.1 177325.0 178004.0 178921.0 179643.0 41434.2 41535.8 41666.4 41701.2 41729.6 1199.2 1243.6 1245.1 1248.6 1262.4 19423.8 19642.4 20006.0 20477.8 20875.2 29992.9 30109.0 30301.0 30338.4 30372.8 155466.0 156591.0 156725.0 156849.0 156963.0 29305.7 29506.9 29655.3 31251.8 31395.5 37416.0 37511.4 37750.8 37899.2 38121.4 27044.9 27970.7 28389.1 28504.3 28603.9 27518.6 27802.7 28472.0 29502.6 29914.0 31016.8 31260.5 31285.2 31300.4 31336.0 26189.7 27322.9 27427.0 27473.9 27767.9 20552.3 22545.0 22624.2 22668.7 22737.9 16:58:22 INFO - PID 4929 | 16:58:22 INFO - PID 4929 | [#2] jquery.html Cycles:180 Average:25577.24 Median:22284.00 stddev:63514.81 (285.0%) stddev-sans-first:63664.89 16:58:22 INFO - PID 4929 | Values: 414.6 418.6 426.8 431.4 445.5 14911.6 16929.9 17759.6 17787.2 18057.7 18589.9 18721.2 18931.8 19450.8 19630.8 5474.7 5534.9 5576.7 5615.3 5625.0 5497.8 5577.3 5584.8 5597.9 5612.2 5286.1 5455.4 5508.6 5514.8 5551.9 5504.5 5531.1 5551.7 5551.8 5567.3 4904.5 4946.1 4967.4 4974.2 4987.0 21594.0 21624.6 22270.0 22298.0 22474.0 1616.9 1629.0 1645.6 1647.5 1659.0 14614.1 14654.5 15107.2 15133.5 15287.8 1984.6 2031.3 2057.6 2067.5 2072.2 87398.0 87421.0 88873.0 89149.0 91581.0 10973.6 11035.6 11163.3 11288.9 11407.1 15526.0 15796.0 15815.6 15820.6 15908.4 7559.1 7566.5 7616.1 7816.0 7856.5 6831.3 6955.3 7172.7 7174.8 7219.2 6937.7 7016.6 7091.4 7144.2 7159.9 2104.2 2113.1 2116.0 2122.4 2256.7 844.5 889.6 890.9 905.6 918.9 17137.6 17231.6 17556.3 17985.7 18333.1 880.0 885.1 893.0 897.5 906.5 9355.4 9616.5 9899.7 9945.3 9994.8 377015.0 379113.0 381360.0 383836.0 388262.5 76836.0 77616.0 78241.6 78956.6 80477.6 1027.3 1029.3 1057.8 1063.3 1066.1 14333.5 14487.4 14759.8 15020.5 15256.5 39292.6 40028.6 40521.0 41338.6 43865.8 51090.7 52317.2 53010.0 54069.5 54821.0 10239.5 10640.4 10652.8 10719.4 10948.9 11306.7 11538.7 11807.3 11952.5 12029.2 10261.4 10347.3 10427.7 10431.8 10625.1 9959.5 10061.1 10077.4 10317.8 10649.1 10941.8 11100.2 11248.2 11453.1 11568.3 11342.5 11627.5 11733.2 11972.1 11988.3 10900.3 10980.2 11273.8 11313.8 11466.4 16:58:22 INFO - PID 4929 | 16:58:22 INFO - PID 4929 | [#3] mootools.html Cycles:180 Average:6367.09 Median:3418.93 stddev:4136.78 (121.0%) stddev-sans-first:4130.36 16:58:22 INFO - PID 4929 | Values: 1227.3 1271.1 1286.5 1291.7 1303.0 10017.7 10492.6 10493.3 10542.3 10743.1 10550.3 10602.2 10750.3 10752.9 10757.4 2231.1 2262.6 2267.6 2267.6 2271.1 2205.0 2257.8 2259.1 2264.1 2271.4 2214.4 2225.4 2267.9 2269.4 2270.2 2138.4 2172.3 2208.5 2222.2 2225.9 1838.0 1920.9 2141.8 2225.1 2243.9 10286.8 10338.6 10342.9 10386.8 10392.0 5605.8 5765.3 5795.4 5802.0 5813.4 3193.4 3402.2 3483.1 3486.7 3495.2 1044.2 1062.1 1084.7 1085.7 1091.2 15384.7 15523.9 15543.6 15547.0 15559.8 4668.3 4698.8 4942.0 5006.4 5022.0 10923.5 10936.5 10946.4 10955.8 10983.1 5698.2 5698.4 5708.8 5710.9 5712.1 5038.1 5163.2 5218.5 5242.4 5294.2 3783.4 3853.2 3883.5 3934.7 3977.4 1115.2 1137.2 1137.7 1151.1 1152.0 717.2 734.6 743.1 750.6 756.9 5595.1 5639.8 5796.5 5847.2 5879.3 535.3 555.0 559.5 559.5 563.4 3247.8 3316.0 3363.1 3402.8 3435.1 12973.4 13258.3 13770.2 13784.9 13816.4 13122.2 13328.2 13454.2 13508.9 13554.9 7421.6 7502.1 7654.3 7667.3 7709.2 5522.1 5524.0 5558.7 6695.8 6707.5 11924.5 11939.2 11948.2 12081.7 12367.7 12003.5 12209.9 12249.5 12465.9 12572.8 8372.7 8434.4 8466.4 8522.0 8524.7 9540.7 9631.2 9632.5 9637.2 9658.9 8032.3 8033.8 8101.6 8176.6 8199.5 7935.6 7984.0 8020.2 8086.2 8100.9 7582.7 7599.9 7620.0 7685.1 7688.9 5405.9 5450.3 5513.3 5517.2 5552.6 5151.6 5215.6 5390.8 5395.1 5487.6 16:58:22 INFO - PID 4929 | 16:58:22 INFO - PID 4929 | [#4] prototype.html Cycles:180 Average:4812.78 Median:5478.95 stddev:1448.58 (26.4%) stddev-sans-first:1413.77 16:58:22 INFO - PID 4929 | Values: 371.5 372.3 379.9 381.0 384.6 5461.6 5509.5 5524.4 5525.3 5625.7 5297.6 5456.5 5459.7 5476.4 5481.5 4039.1 4145.3 4148.9 4155.8 4167.2 4125.9 4141.2 4146.9 4147.1 4154.2 4117.2 4117.9 4125.2 4132.3 4133.8 4063.8 4106.1 4122.7 4123.5 4140.3 3573.0 3583.2 3584.8 3591.4 3599.8 5327.9 5369.8 5400.2 5402.1 5403.6 4755.5 4806.1 4847.5 4856.4 4869.2 5722.6 5771.5 5777.7 5781.2 5798.7 4002.9 4008.6 4016.5 4018.2 4026.2 6051.3 6167.2 6194.2 6240.8 6246.8 4102.2 4115.6 4126.0 4129.0 4136.8 4176.3 4182.3 4183.1 4183.3 4194.4 4655.0 4834.5 4838.5 4840.3 4841.5 5860.3 5874.1 5878.4 5889.2 5909.8 1409.5 1444.0 1448.9 1450.8 1455.4 907.5 954.1 959.8 975.2 975.3 5095.8 5132.7 5138.2 5139.5 5142.6 6073.2 6079.5 6109.3 6126.2 6141.8 4927.0 5092.8 5115.5 5116.6 5149.5 3186.9 3208.6 3211.6 3222.1 3230.2 5475.8 5615.4 5621.4 5625.8 5652.0 6039.6 6232.4 6290.6 6309.5 6320.5 5623.3 5630.2 5633.1 5650.5 5678.9 5979.7 6097.4 6119.4 6120.2 6127.2 5819.8 5984.7 6032.6 6038.8 6040.2 5648.3 5655.6 5668.8 5672.5 5684.6 4041.9 4122.6 4145.6 4156.1 4163.1 6096.9 6128.8 6130.8 6140.9 6145.5 5870.7 5894.3 5913.9 5916.3 5928.4 5739.0 5848.6 5855.0 5862.9 5911.8 5772.3 5796.9 5804.0 5830.5 5848.3 5836.5 5871.8 5917.8 5923.3 5954.2 5763.6 5800.4 5807.3 5809.4 5835.3 16:58:22 INFO - PID 4929 | 16:58:22 INFO - PID 4929 | [#5] yui.html Cycles:180 Average:15357.87 Median:18046.40 stddev:18476.76 (102.4%) stddev-sans-first:18495.62 16:58:22 INFO - PID 4929 | Values: 658.4 669.3 674.8 679.6 680.5 11686.0 12079.8 12084.7 14536.3 15738.1 14776.3 14922.6 15001.4 15091.5 15912.4 845.2 862.4 865.1 865.6 870.0 860.7 894.8 915.8 918.9 921.8 949.8 950.1 950.6 954.0 954.7 936.4 942.8 944.9 945.2 947.9 697.2 699.4 700.0 701.6 703.0 12657.3 12772.6 12837.7 12890.5 12895.5 15789.7 15852.8 16038.7 16163.5 16202.4 11350.4 11607.3 11703.9 11723.7 11785.7 2781.1 2818.0 2831.6 2832.7 2839.4 69837.7 71139.6 71162.0 72064.0 73195.3 12252.5 12258.9 12337.9 12369.9 12425.9 20585.9 22030.2 22034.1 22178.2 22363.0 5822.9 6030.9 6082.0 6091.7 6262.7 2946.7 2991.3 2992.8 2996.0 2998.6 3227.2 3285.6 3324.8 3325.5 3357.7 705.5 718.2 722.2 723.0 724.4 588.7 593.8 597.5 602.7 619.7 9242.4 9957.4 10144.8 10293.2 10420.8 539.9 544.1 549.3 553.1 555.9 4409.1 4423.8 4454.7 4572.5 4585.8 63808.0 64407.7 64499.4 66318.0 67515.0 57390.7 58534.7 59803.4 60225.4 63306.0 51651.0 51770.5 52119.7 52519.5 52708.4 8794.4 8812.0 8836.4 8844.6 9045.7 26607.9 27073.2 27483.9 27632.6 28089.9 32722.5 32807.2 32991.5 33164.5 34140.1 10797.8 13462.5 13748.4 13819.7 13939.2 14894.9 15768.8 15933.8 16668.0 16986.1 14024.2 15202.8 15240.3 15621.1 15640.8 13816.7 13950.0 14689.4 15185.6 15341.7 13393.4 13396.5 13591.9 13830.7 13917.4 10182.0 10199.8 10416.5 10566.2 10624.5 11573.5 12269.5 12302.3 12304.9 12313.6 16:58:22 INFO - PID 4929 | -------- Summary: end -------- 16:58:22 INFO - PID 4929 | 16:58:23 INFO - TEST-INFO | 4929: exit 0 16:58:23 INFO - TEST-OK | dromaeo_css | took 1119890ms 16:58:23 INFO - TEST-START | kraken 16:58:23 INFO - operating with platform_type : linux_ 16:58:23 INFO - Initialising browser for kraken test... 16:58:23 INFO - TEST-INFO | started process 5347 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO_qOp7/profile http://localhost:49503/getInfo.html) 16:58:23 INFO - PID 5347 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 16:58:27 INFO - PID 5347 | __metrics Screen width/height:1600/1200 16:58:27 INFO - PID 5347 | colorDepth:24 16:58:27 INFO - PID 5347 | Browser inner width/height: 1024/695 16:58:27 INFO - PID 5347 | __metrics 16:58:29 INFO - TEST-INFO | 5347: exit 0 16:58:29 INFO - Browser initialized. 16:58:29 INFO - Running cycle 1/1 for kraken test... 16:58:29 INFO - TEST-INFO | started process 5518 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO_qOp7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:58:40 INFO - PID 5518 | RSS: Main: 161353728 16:58:40 INFO - PID 5518 | 16:58:43 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49503/tests/kraken/driver.html?testName=audio-beat-detection) 16:58:44 INFO - PID 5518 | RSS: Main: 167223296 16:58:44 INFO - PID 5518 | 16:58:47 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49503/tests/kraken/driver.html?testName=audio-dft) 16:58:47 INFO - PID 5518 | RSS: Main: 165908480 16:58:47 INFO - PID 5518 | 16:58:50 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49503/tests/kraken/driver.html?testName=audio-fft) 16:58:51 INFO - PID 5518 | RSS: Main: 161955840 16:58:51 INFO - PID 5518 | 16:58:53 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49503/tests/kraken/driver.html?testName=audio-oscillator) 16:58:54 INFO - PID 5518 | RSS: Main: 162021376 16:58:54 INFO - PID 5518 | 16:58:56 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49503/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:58:56 INFO - PID 5518 | RSS: Main: 162938880 16:58:56 INFO - PID 5518 | 16:58:59 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49503/tests/kraken/driver.html?testName=imaging-darkroom) 16:59:00 INFO - PID 5518 | RSS: Main: 166801408 16:59:00 INFO - PID 5518 | 16:59:04 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49503/tests/kraken/driver.html?testName=imaging-desaturate) 16:59:04 INFO - PID 5518 | RSS: Main: 164098048 16:59:04 INFO - PID 5518 | 16:59:07 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49503/tests/kraken/driver.html?testName=json-parse-financial) 16:59:08 INFO - PID 5518 | RSS: Main: 165691392 16:59:08 INFO - PID 5518 | 16:59:09 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49503/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:59:10 INFO - PID 5518 | RSS: Main: 165232640 16:59:10 INFO - PID 5518 | 16:59:12 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49503/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:59:12 INFO - PID 5518 | RSS: Main: 165416960 16:59:12 INFO - PID 5518 | 16:59:15 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49503/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:59:15 INFO - PID 5518 | RSS: Main: 164716544 16:59:15 INFO - PID 5518 | 16:59:18 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49503/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:59:19 INFO - PID 5518 | RSS: Main: 165228544 16:59:19 INFO - PID 5518 | 16:59:22 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49503/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:59:23 INFO - PID 5518 | RSS: Main: 165257216 16:59:23 INFO - PID 5518 | 16:59:24 INFO - PID 5518 | Cycle 1(1): loaded http://localhost:49503/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49503/tests/kraken/driver.html?testName=ai-astar) 16:59:25 INFO - PID 5518 | RSS: Main: 166305792 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | __start_tp_report 16:59:25 INFO - PID 5518 | _x_x_mozilla_page_load 16:59:25 INFO - PID 5518 | _x_x_mozilla_page_load_details 16:59:25 INFO - PID 5518 | |i|pagename|runs| 16:59:25 INFO - PID 5518 | |0;ai-astar;106;112;112;112;110;113;111;111;110;114 16:59:25 INFO - PID 5518 | |1;audio-beat-detection;153;125;132;181;123;126;130;123;151;123 16:59:25 INFO - PID 5518 | |2;audio-dft;166;164;164;164;162;166;172;163;168;168 16:59:25 INFO - PID 5518 | |3;audio-fft;75;76;77;105;77;77;76;76;98;76 16:59:25 INFO - PID 5518 | |4;audio-oscillator;87;84;108;83;83;82;97;83;82;105 16:59:25 INFO - PID 5518 | |5;imaging-gaussian-blur;113;114;116;114;114;116;113;114;114;121 16:59:25 INFO - PID 5518 | |6;imaging-darkroom;150;151;152;150;151;151;149;151;150;151 16:59:25 INFO - PID 5518 | |7;imaging-desaturate;95;95;94;95;99;94;95;94;95;97 16:59:25 INFO - PID 5518 | |8;json-parse-financial;55;54;56;55;55;56;56;55;59;61 16:59:25 INFO - PID 5518 | |9;json-stringify-tinderbox;56;55;55;55;59;57;56;55;55;55 16:59:25 INFO - PID 5518 | |10;stanford-crypto-aes;84;81;81;83;91;90;84;82;81;83 16:59:25 INFO - PID 5518 | |11;stanford-crypto-ccm;140;135;138;133;134;133;149;134;181;131 16:59:25 INFO - PID 5518 | |12;stanford-crypto-pbkdf2;197;192;191;188;200;193;193;192;198;199 16:59:25 INFO - PID 5518 | |13;stanford-crypto-sha256-iterative;80;77;75;78;74;76;72;71;76;74 16:59:25 INFO - PID 5518 | __end_tp_report 16:59:25 INFO - PID 5518 | __start_cc_report 16:59:25 INFO - PID 5518 | _x_x_mozilla_cycle_collect,777 16:59:25 INFO - PID 5518 | __end_cc_report 16:59:25 INFO - PID 5518 | __startTimestamp1494892765147__endTimestamp 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | ------- Summary: start ------- 16:59:25 INFO - PID 5518 | Number of tests: 14 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:2.18 (1.9%) stddev-sans-first:1.32 16:59:25 INFO - PID 5518 | Values: 106.0 112.0 112.0 112.0 110.0 113.0 111.0 111.0 110.0 114.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:131.00 stddev:19.19 (14.6%) stddev-sans-first:19.43 16:59:25 INFO - PID 5518 | Values: 153.0 125.0 132.0 181.0 123.0 126.0 130.0 123.0 151.0 123.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#2] audio-dft Cycles:10 Average:165.70 Median:166.00 stddev:2.98 (1.8%) stddev-sans-first:3.16 16:59:25 INFO - PID 5518 | Values: 166.0 164.0 164.0 164.0 162.0 166.0 172.0 163.0 168.0 168.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.50 stddev:10.79 (14.1%) stddev-sans-first:11.20 16:59:25 INFO - PID 5518 | Values: 75.0 76.0 77.0 105.0 77.0 77.0 76.0 76.0 98.0 76.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:83.00 stddev:10.08 (12.1%) stddev-sans-first:10.65 16:59:25 INFO - PID 5518 | Values: 87.0 84.0 108.0 83.0 83.0 82.0 97.0 83.0 82.0 105.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:114.00 stddev:2.38 (2.1%) stddev-sans-first:2.42 16:59:25 INFO - PID 5518 | Values: 113.0 114.0 116.0 114.0 114.0 116.0 113.0 114.0 114.0 121.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:151.00 stddev:0.84 (0.6%) stddev-sans-first:0.87 16:59:25 INFO - PID 5518 | Values: 150.0 151.0 152.0 150.0 151.0 151.0 149.0 151.0 150.0 151.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:1.57 (1.6%) stddev-sans-first:1.66 16:59:25 INFO - PID 5518 | Values: 95.0 95.0 94.0 95.0 99.0 94.0 95.0 94.0 95.0 97.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:2.15 (3.8%) stddev-sans-first:2.24 16:59:25 INFO - PID 5518 | Values: 55.0 54.0 56.0 55.0 55.0 56.0 56.0 55.0 59.0 61.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#9] json-stringify-tinderbox Cycles:10 Average:55.80 Median:55.50 stddev:1.32 (2.4%) stddev-sans-first:1.39 16:59:25 INFO - PID 5518 | Values: 56.0 55.0 55.0 55.0 59.0 57.0 56.0 55.0 55.0 55.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:83.50 stddev:3.62 (4.3%) stddev-sans-first:3.84 16:59:25 INFO - PID 5518 | Values: 84.0 81.0 81.0 83.0 91.0 90.0 84.0 82.0 81.0 83.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#11] stanford-crypto-ccm Cycles:10 Average:140.80 Median:136.50 stddev:15.04 (11.0%) stddev-sans-first:15.95 16:59:25 INFO - PID 5518 | Values: 140.0 135.0 138.0 133.0 134.0 133.0 149.0 134.0 181.0 131.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.30 Median:195.00 stddev:3.95 (2.0%) stddev-sans-first:4.06 16:59:25 INFO - PID 5518 | Values: 197.0 192.0 191.0 188.0 200.0 193.0 193.0 192.0 198.0 199.0 16:59:25 INFO - PID 5518 | 16:59:25 INFO - PID 5518 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:76.00 stddev:2.71 (3.6%) stddev-sans-first:2.28 16:59:25 INFO - PID 5518 | Values: 80.0 77.0 75.0 78.0 74.0 76.0 72.0 71.0 76.0 74.0 16:59:25 INFO - PID 5518 | -------- Summary: end -------- 16:59:25 INFO - PID 5518 | 16:59:26 INFO - TEST-INFO | 5518: exit 0 16:59:26 INFO - TEST-OK | kraken | took 63155ms 16:59:26 INFO - SUITE-END | took 1183s 16:59:26 INFO - Completed test suite (00:19:43) 16:59:26 INFO - javascript benchmark 16:59:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6772.289886432748, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2744.245332533553, 2749.5188342040137, 2779.902703405381, 2793.818401803883, 2795.035712679125, 5259.237410575464, 5368.570514358852, 5376.838694839152, 5399.244105825188, 5407.540359069479, 5322.920156197651, 5355.169948657955, 5446.128619420893, 5468.125099984003, 5469.507744303012, 4797.2324428091515, 4805.855824325258, 4813.3983252093485, 4869.902601947959, 4902.044101400228, 4792.6405519586, 4798.160321943664, 4901.901961960759, 4915.803367865299, 4950.381202349707, 4714.269288260325, 4819.349387832633, 4821.4696383397795, 4902.142125128106, 4910.680805747633, 4765.046990601893, 4771.928421073687, 4812.181929072066, 4823.03539292143, 4877.365942427462, 4692.7653617319, 4768.809247630091, 4785.880352991168, 4791.185498465269, 4847.51524847516, 5347.491988261109, 5351.812686555952, 5374.758135883855, 5385.219143224211, 5399.460053994609, 5620.325560932714, 5642.407547207526, 5662.462066103713, 5667.773289068474, 5746.224259724925, 5708.029634962067, 5731.168980497846, 5739.3399759027825, 5760.654360738369, 5763.106718458645, 5290.576753859682, 5314.12316967696, 5321.095413779667, 5331.760070796823, 5368.2752828367675, 5885.61743486672, 5900.881982360329, 5904.0, 5904.911426328608, 5908.99547076998, 5644.8024319148635, 5648.068068768689, 5664.915026274609, 5666.9716651416475, 5667.631603945731, 5745.741441635137, 5758.884822303614, 5762.30852297727, 5764.13537969308, 5765.336986246635, 5776.942230577641, 5779.104238842986, 5796.710164491758, 5802.506786923159, 5837.6497410155525, 5789.8263052108505, 5791.652500849962, 5793.768249269982, 5799.94200057994, 5801.506871915934, 4375.693701440932, 4418.80115394808, 4428.645708343325, 4484.417025786627, 4517.909641807146, 3921.8627348042683, 3938.074552480165, 3940.4680368149934, 3964.4647972523917, 3998.420229066769, 4990.351254336913, 4997.700137991732, 5003.899922001539, 5005.724685142352, 5013.771625951666, 5678.716064196774, 5747.971260143672, 5851.707414629252, 5867.237259156283, 5928.110783382527, 4925.285833554186, 4927.211646136601, 4930.9506904930495, 4934.481879402683, 4966.950330496721, 5010.549050585465, 5127.512886275872, 5127.564157046693, 5129.461406552333, 5130.204818253177, 5325.254464375068, 5327.147656374961, 5332.0402327581405, 5332.386775520709, 5335.466453354788, 5701.4298570142655, 5859.9121013183985, 5911.290645122441, 5921.5558833086825, 5947.791827286024, 5385.4076051635075, 5467.0, 5468.48049435303, 5468.6171967963455, 5470.753816078366, 5570.053090974625, 5653.180288858175, 5723.3418156912485, 5777.45114214149, 5780.323706125177, 5702.0306547887785, 5759.39526349744, 5885.146653735099, 5894.793682221101, 5898.1152827076285, 5569.55443564522, 5573.442655734394, 5635.211070450058, 5750.9648263313, 5751.309842818889, 5645.604807663424, 5669.773209071591, 5713.371529131709, 5714.428557144419, 5725.055365864586, 5639.2668953035845, 5644.209810626434, 5645.350784659652, 5647.887042259214, 5649.37856835756, 5708.942910570841, 5735.225744524436, 5737.340205876378, 5751.396103409082, 5754.482096611325, 5510.366307874486, 5540.916886246629, 5545.667259964415, 5564.304461942257, 5577.637453565505], "value": 5236.517993282518, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.4631119282923, 758.6434375843353, 1013.1084645511949, 1029.1201023125222, 1148.2019996102701, 33499.83250083749, 34359.65640343596, 34458.483122753176, 34581.96254112377, 34583.13542161441, 31973.84013079937, 32298.838505807496, 32346.514802277925, 32496.0, 32510.67489325106, 6409.8397540061405, 6483.7082331295205, 6606.801795946105, 6609.3390660933765, 7129.287071292855, 6981.755638552626, 7080.398166155883, 7082.220955694869, 7086.610236436994, 7092.5389849659605, 7049.118860142482, 7051.612161331124, 7119.074520312352, 7120.786376408716, 7126.073610430637, 7037.401820845209, 7056.329648683367, 7086.929130708679, 7093.219745828006, 7095.325944035309, 7010.964945175241, 7018.5788852669, 7039.859202815915, 7052.2595127511895, 7074.398676112485, 32676.6732332676, 32849.50725739116, 32894.34211315784, 32953.67046329554, 33096.50355244649, 2918.649762028549, 2923.8830446782104, 2946.1603443018853, 2958.4822656035108, 2973.2864112612815, 25115.120970765936, 25212.24363269105, 25301.240962771146, 25410.0, 25446.491070178494, 3086.459869522847, 3111.9377612447624, 3127.890523831678, 3142.9842850785826, 3150.9054728358187, 146192.0, 146712.99999999892, 147407.0, 147662.0, 147663.99999999892, 37788.24423511514, 39387.0, 39755.20489590192, 40762.184756304705, 41171.58828411737, 41983.70244463355, 42061.78969105196, 42110.57889421067, 42148.367774483406, 42238.788806055774, 12439.689007774878, 12624.558140465224, 12630.052746044083, 12637.87362126367, 12655.17741346828, 10067.59729610822, 10125.341852779546, 10127.898721012843, 10133.391996480235, 10135.594576216869, 12769.553065642658, 12809.295488748208, 12811.551595694105, 12864.356782160854, 13056.498502672124, 5213.452587478336, 5266.104762190437, 5278.81524146653, 5290.761915713725, 5350.3312085989255, 867.9696210632598, 905.9943462756336, 915.514777168102, 919.7102912582515, 939.6476321379482, 20344.0, 20643.58712825735, 21126.0, 21373.0, 21573.67639485409, 934.4907025671025, 993.3493561717087, 999.8000399920045, 1000.3397757480028, 1003.5484032185545, 10625.574977000988, 11298.378589177673, 11315.490802913888, 11581.88418115808, 11660.416979151008, 176607.11696441437, 177325.0, 178004.0, 178921.0, 179643.0, 41434.1713165735, 41535.7923210382, 41666.37500437496, 41701.1659766803, 41729.582704173175, 1199.244475980127, 1243.5585367194515, 1245.0724210463263, 1248.5754843353304, 1262.4319056424645, 19423.8057619422, 19642.410727678194, 20006.0, 20477.79522204759, 20875.164993400092, 29992.85003574968, 30109.0, 30301.0, 30338.393232135677, 30372.84813575918, 155466.0, 156591.0, 156725.0, 156849.0, 156963.0, 29305.706942930297, 29506.852465737535, 29655.258618533848, 31251.84374078115, 31395.529067063555, 37416.0, 37511.43732843955, 37750.811245943594, 37899.242015160096, 38121.428178577895, 27044.864775675996, 27970.720292797625, 28389.148325550268, 28504.287392815346, 28603.85698071496, 27518.587221192098, 27802.721972780822, 28472.0, 29502.55746163766, 29914.0, 31016.844915775277, 31260.531092033183, 31285.217869553442, 31300.37399252048, 31336.0, 26189.73810261873, 27322.863385682944, 27427.0, 27473.86263068752, 27767.861160694876, 20552.28067017707, 22545.0, 22624.208152714575, 22668.659970100132, 22737.88631056834], "value": 14640.32555429336, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.60405312926156, 418.58141858141875, 426.78873957391096, 431.35297054418373, 445.50326386079513, 14911.552653420387, 16929.915350423245, 17759.556011099714, 17787.199576019077, 18057.729134062967, 18589.907050464764, 18721.15754791034, 18931.810681893177, 19450.805491945077, 19630.803691963076, 5474.698891560963, 5534.944650553493, 5576.665400075988, 5615.326160860703, 5625.043742563754, 5497.835064948058, 5577.3028371453565, 5584.832455026335, 5597.888042239153, 5612.214289999403, 5286.074936886049, 5455.372632147294, 5508.586855985797, 5514.80698175564, 5551.944480555183, 5504.4770746778995, 5531.059719847635, 5551.72241387931, 5551.77792888284, 5567.3040869891265, 4904.485029071968, 4946.109700253953, 4967.3542439482635, 4974.204127339608, 4987.0, 21594.0, 21624.567508649896, 22269.991401547715, 22298.0, 22474.0, 1616.9029858208546, 1629.030726717601, 1645.6050547868545, 1647.5222185566172, 1658.9834101659071, 14614.123152610771, 14654.487092951695, 15107.169105699182, 15133.470328538559, 15287.847121528754, 1984.5534754680225, 2031.3296612118113, 2057.6399130152313, 2067.4831292176955, 2072.1504183284933, 87398.0, 87421.0, 88873.0, 89149.0, 91581.0, 10973.561057557768, 11035.613753518588, 11163.330200188015, 11288.943555282336, 11407.08743300534, 15526.0, 15796.0, 15815.604609884615, 15820.60448488797, 15908.363665453253, 7559.130699969464, 7566.508176968479, 7616.124145723313, 7816.0, 7856.45004849655, 6831.28271531492, 6955.269696681877, 7172.713091476387, 7174.820629484305, 7219.241979592172, 6937.68780404883, 7016.649167541603, 7091.404434002308, 7144.178419481722, 7159.89260161098, 2104.1583366653463, 2113.0702490903955, 2115.978840211609, 2122.373899699592, 2256.6953461282847, 844.472204871955, 889.6174644902754, 890.8663700444985, 905.5789058088021, 918.8989211186764, 17137.571560710832, 17231.56921076983, 17556.29774808993, 17985.65107616934, 18333.083345832656, 879.9868001979847, 885.0927799006071, 892.9642814287486, 897.4704924094815, 906.4742449379344, 9355.38278825133, 9616.51917404141, 9899.65351212704, 9945.303828732063, 9994.750131246778, 377015.0, 379113.0, 381360.0, 383836.0, 388262.52587164054, 76836.0, 77616.0, 78241.60879195567, 78956.60521697355, 80477.59761201157, 1027.3219675014454, 1029.25893356783, 1057.799018186542, 1063.3035361838008, 1066.0831684751108, 14333.498327558485, 14487.420503180176, 14759.778603320745, 15020.474283400466, 15256.542303730906, 39292.60707392889, 40028.599714002485, 40521.0, 41338.58661413348, 43865.78067109772, 51090.744546277034, 52317.215241772166, 53010.0, 54069.45930540644, 54821.0, 10239.48802559854, 10640.41477718702, 10652.786944261226, 10719.356838589709, 10948.890511094787, 11306.660800176009, 11538.711532211762, 11807.291562506278, 11952.462139203582, 12029.218100823418, 10261.384316940708, 10347.327423717434, 10427.739306517398, 10431.791364172826, 10625.149988000787, 9959.502024898871, 10061.094501494608, 10077.445740484343, 10317.793644127225, 10649.094826939487, 10941.835872461761, 11100.167487438313, 11248.156388270749, 11453.141014424118, 11568.305901645928, 11342.546298148312, 11627.534898604299, 11733.237339572901, 11972.102092343282, 11988.280703157838, 10900.291481053706, 10980.231383803057, 11273.830892536454, 11313.7737245253, 11466.356200370145], "value": 9027.736408485454, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1227.324971265804, 1271.0721173543313, 1286.5304163980145, 1291.696451333937, 1303.042263936007, 10017.699469015932, 10492.632757853477, 10493.265471416995, 10542.314749541274, 10743.1405487561, 10550.314229575073, 10602.151827853773, 10750.301230420017, 10752.946235268832, 10757.408342541177, 2231.129859354853, 2262.6040442922504, 2267.5918334699745, 2267.5918334699745, 2271.114265436481, 2204.9559008819815, 2257.7516473187934, 2259.1076524772707, 2264.1283106004144, 2271.4207876991345, 2214.402111429913, 2225.388018294974, 2267.9433014174697, 2269.4326418395403, 2270.1713874435877, 2138.4012476506605, 2172.3048624440107, 2208.5141268920893, 2222.1777942161502, 2225.9109635614477, 1837.9724304135448, 1920.875143115693, 2141.8286537076997, 2225.121077174523, 2243.876586787726, 10286.794264114751, 10338.638147664795, 10342.896571034269, 10386.844197337046, 10392.0, 5605.775768969236, 5765.279340082489, 5795.449432303966, 5801.970990145064, 5813.4477224664015, 3193.4483186499565, 3402.2004828865197, 3483.1466290758926, 3486.6513348665185, 3495.2310491691787, 1044.2272718188563, 1062.1131355318319, 1084.6529110684585, 1085.7459634122602, 1091.154355374583, 15384.692306153926, 15523.91332606718, 15543.611409714735, 15546.999999999887, 15559.766603500844, 4668.323093151474, 4698.788554515055, 4941.998490611309, 5006.4492905780335, 5022.020705962303, 10923.508442120125, 10936.507857146447, 10946.39794811293, 10955.83566246507, 10983.066439352584, 5698.202251684767, 5698.430156984268, 5708.828735137952, 5710.914336284959, 5712.0860662293835, 5038.143515602356, 5163.225516172529, 5218.530332270114, 5242.370915490165, 5294.152935530296, 3783.375743002429, 3853.2486165197515, 3883.5339759229073, 3934.724569280123, 3977.443157957888, 1115.2360632966445, 1137.181229514748, 1137.6643890052455, 1151.1366475143643, 1151.9769604607861, 717.1859938969308, 734.5886303669962, 743.0674503498076, 750.579675381788, 756.8940348351125, 5595.073470159286, 5639.802606908738, 5796.478316951495, 5847.181394604759, 5879.323877754113, 535.253321616343, 555.0370107862855, 559.5132234955614, 559.5495626021011, 563.352145033215, 3247.7888937219004, 3315.966840331566, 3363.1423986883187, 3402.809016844085, 3435.055359776082, 12973.416196271002, 13258.337083145803, 13770.24263665508, 13784.931075344559, 13816.37826297819, 13122.212667239995, 13328.200307981551, 13454.192748434929, 13508.86491135076, 13554.932225339007, 7421.628918554051, 7502.137254215726, 7654.349380302625, 7667.348275396654, 7709.190534993857, 5522.088855338824, 5524.011355911788, 5558.694271815089, 6695.832604184934, 6707.5304728668525, 11924.465731394755, 11939.164258501822, 11948.192174838732, 12081.697957551132, 12367.69080772952, 12003.459844307201, 12209.938950305192, 12249.510019599473, 12465.875341246834, 12572.811407828707, 8372.66509339644, 8434.367422443218, 8466.365022623204, 8521.957390213258, 8524.74425767228, 9540.666076687283, 9631.229501639711, 9632.518374081128, 9637.18083962871, 9658.90341096608, 8032.277095061472, 8033.758987230392, 8101.635426405944, 8176.550289734122, 8199.549024803693, 7935.5635440049045, 7983.960080199562, 8020.197980201933, 8086.1509541499645, 8100.878486822585, 7582.696692132253, 7599.924000759922, 7620.047494063242, 7685.116211635734, 7688.884666730115, 5405.891882162414, 5450.31871016123, 5513.255710479034, 5517.227588137584, 5552.555795536428, 5151.562117219928, 5215.608829337891, 5390.811321603882, 5395.136778115482, 5487.64330318528], "value": 4733.095009261845, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.46323562452255, 372.2907860525698, 379.85755341746847, 380.9923801523969, 384.6499685286388, 5461.590380721452, 5509.504144626982, 5524.447555244474, 5525.2817133772605, 5625.7497001199545, 5297.576193904488, 5456.536194423479, 5459.727013649322, 5476.3702174249875, 5481.5340696040885, 4039.131586708857, 4145.336746120622, 4148.937765933513, 4155.833766649345, 4167.249895018896, 4125.855595054174, 4141.233871733723, 4146.880342307575, 4147.149834283965, 4154.231467178582, 4117.197146556423, 4117.938230926538, 4125.21620892031, 4132.2768515509815, 4133.772642504661, 4063.773675377604, 4106.096658735073, 4122.732022418533, 4123.463949686537, 4140.254754144253, 3572.99956012317, 3583.175869549992, 3584.8028358440274, 3591.3894637911358, 3599.8020108894, 5327.946720532784, 5369.812056578001, 5400.162974738922, 5402.108652072403, 5403.595400046981, 4755.524447555252, 4806.086843499724, 4847.51524847516, 4856.441509226449, 4869.172240719086, 5722.599418040739, 5771.50942169916, 5777.740001699892, 5781.248437703071, 5798.7390567424145, 4002.899927501836, 4008.5590585035625, 4016.49793775778, 4018.1963607278367, 4026.2350153470748, 6051.304100028509, 6167.198264225667, 6194.163787888668, 6240.785995212424, 6246.750129994794, 4102.2410853992105, 4115.5678653741525, 4125.958740412558, 4128.979355103205, 4136.772477513706, 4176.2900306947895, 4182.2890108681595, 4183.142455796555, 4183.267928112568, 4194.433751443577, 4654.953450465519, 4834.540718631724, 4838.491958344323, 4840.273958906192, 4841.491643377465, 5860.326062502867, 5874.118882167623, 5878.413498060925, 5889.23439952812, 5909.793157239476, 1409.4714482069223, 1444.0, 1448.8840892728767, 1450.804141440913, 1455.3814628782725, 907.5054095517959, 954.0936110694868, 959.7984423271051, 975.2100798353357, 975.2831668723483, 5095.7578665387855, 5132.717700526432, 5138.152204886227, 5139.486051394905, 5142.611494896431, 6073.240844894388, 6079.513638908877, 6109.2974307955155, 6126.172966649535, 6141.846453838601, 4926.989967056745, 5092.796288148506, 5115.465360391942, 5116.590672746172, 5149.485051494896, 3186.9362612747614, 3208.6363295599276, 3211.5985501812274, 3222.1300248932657, 3230.1924518870283, 5475.780968761284, 5615.438456154352, 5621.409751976065, 5625.803096891589, 5652.0, 6039.577229593974, 6232.407921247474, 6290.591111577733, 6309.526785491106, 6320.462760665395, 5623.268975033301, 5630.18362337467, 5633.126865335879, 5650.463205995506, 5678.8828099627435, 5979.701014949323, 6097.390260973867, 6119.44924956756, 6120.234970628671, 6127.20346354971, 5819.76720931158, 5984.713286643218, 6032.607880487754, 6038.818835434944, 6040.154378386941, 5648.265725455664, 5655.614374478279, 5668.829935101953, 5672.461116193955, 5684.602077854676, 4041.8989525262105, 4122.618922660822, 4145.647619952218, 4156.147989662051, 4163.12574359388, 6096.939030609815, 6128.754849806136, 6130.785422510191, 6140.907886381798, 6145.477634401126, 5870.735816888165, 5894.263217097863, 5913.876363490923, 5916.319623243382, 5928.407159284037, 5739.044392138136, 5848.561357898089, 5854.970725146347, 5862.853428664147, 5911.7635294587735, 5772.307323121085, 5796.913046304393, 5804.0, 5830.5044071254415, 5848.327442344014, 5836.533077353716, 5871.823845284648, 5917.763289468373, 5923.318818335774, 5954.166416701752, 5763.625364351304, 5800.419958004166, 5807.303123625192, 5809.390014048464, 5835.270591176103], "value": 4361.7586939059665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.3613894522312, 669.2604671837616, 674.8279188806854, 679.6228093408159, 680.4794332335764, 11686.0, 12079.818802717955, 12084.69788255293, 14536.273186340693, 15738.055716657009, 14776.261186940663, 14922.552323430287, 15001.399944002227, 15091.471798487055, 15912.443064492745, 845.2308399356572, 862.3790870573177, 865.0959747064302, 865.6277800545763, 870.0429527519741, 860.6901515454431, 894.8478758611019, 915.8031023329983, 918.8897332320131, 921.8202450522151, 949.8432758594823, 950.116391755667, 950.5627411390768, 953.9952300238524, 954.7231302922179, 936.3773090894547, 942.8444306689422, 944.9196818270456, 945.1965829045291, 947.8957314695378, 697.2365260040248, 699.3845416033857, 700.037448508301, 701.551007355293, 702.9894551581731, 12657.30384828834, 12772.552960646426, 12837.743245135138, 12890.54883079097, 12895.548655797002, 15789.68420631581, 15852.76220856688, 16038.679226415523, 16163.515094547183, 16202.35190592375, 11350.375729334883, 11607.303561786235, 11703.882961170364, 11723.7069073273, 11785.705357366049, 2781.123945957017, 2818.0, 2831.6177316062276, 2832.660080790298, 2839.4463079699467, 69837.65081174612, 71139.64430177868, 71162.0, 72064.0, 73195.26804731938, 12252.509899603916, 12258.938705306417, 12337.876621233672, 12369.876301236873, 12425.875741242653, 20585.89707051455, 22030.228941986952, 22034.11863525473, 22178.223762168247, 22362.993665285103, 5822.883542329129, 6030.939690603125, 6081.969590152021, 6091.66495842724, 6262.718177682015, 2946.7495262902466, 2991.3269514359095, 2992.8054676445963, 2995.9700402996127, 2998.625171853518, 3227.2415982244156, 3285.6221534523356, 3324.7672662913847, 3325.48454989477, 3357.697807197364, 705.5237714542664, 718.2207305074053, 722.227216877946, 722.9710811567584, 724.4023680463533, 588.7497813429276, 593.8277899409123, 597.5189972072524, 602.6625089949642, 619.7304172684896, 9242.445453272825, 9957.352772069793, 10144.797104058025, 10293.176545876311, 10420.791584168273, 539.9352077750616, 544.1212441906292, 549.3490214096308, 553.0818840724459, 555.8804856955774, 4409.140217657526, 4423.8230470781455, 4454.688171827941, 4572.497025327211, 4585.79363928624, 63808.0, 64407.677961609894, 64499.35500645027, 66318.0, 67515.0, 57390.7130464345, 58534.707326463096, 59803.40196598065, 60225.397746022856, 63306.0, 51651.0, 51770.48229517657, 52119.73940130275, 52519.47480525222, 52708.36458177076, 8794.42836215644, 8811.955940220258, 8836.381453298207, 8844.601992910206, 9045.728628141167, 26607.866960665073, 27073.1878043659, 27483.862580687768, 27632.58551121775, 28089.859550702116, 32722.50916236211, 32807.17982050468, 32991.50512742359, 33164.50253246252, 34140.14649633779, 10797.838032429363, 13462.528811491551, 13748.381322840296, 13819.723605527632, 13939.233342166279, 14894.851051489346, 15768.842311576738, 15933.80496462793, 16668.0, 16986.065766382966, 14024.158550487004, 15202.847971520143, 15240.314185861442, 15621.14083725406, 15640.843591563938, 13816.723665526431, 13950.0, 14689.412423503367, 15185.6203594911, 15341.693166136392, 13393.397297121848, 13396.531121410704, 13591.932040339734, 13830.723385532032, 13917.37371818291, 10182.0, 10199.847002294822, 10416.479176041015, 10566.154707623526, 10624.52189651483, 11573.479193436484, 12269.509219631473, 12302.323372214614, 12304.938475307566, 12313.583937847263], "value": 6751.536138427063, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 112.0, 112.0, 112.0, 110.0, 113.0, 111.0, 111.0, 110.0, 114.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 125.0, 132.0, 181.0, 123.0, 126.0, 130.0, 123.0, 151.0, 123.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 164.0, 164.0, 162.0, 166.0, 172.0, 163.0, 168.0, 168.0], "value": 165.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 105.0, 77.0, 77.0, 76.0, 76.0, 98.0, 76.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 108.0, 83.0, 83.0, 82.0, 97.0, 83.0, 82.0, 105.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 116.0, 114.0, 114.0, 116.0, 113.0, 114.0, 114.0, 121.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 152.0, 150.0, 151.0, 151.0, 149.0, 151.0, 150.0, 151.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 99.0, 94.0, 95.0, 94.0, 95.0, 97.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 56.0, 55.0, 55.0, 56.0, 56.0, 55.0, 59.0, 61.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 55.0, 59.0, 57.0, 56.0, 55.0, 55.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 81.0, 83.0, 91.0, 90.0, 84.0, 82.0, 81.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 135.0, 138.0, 133.0, 134.0, 133.0, 149.0, 134.0, 181.0, 131.0], "value": 140.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 192.0, 191.0, 188.0, 200.0, 193.0, 193.0, 192.0, 198.0, 199.0], "value": 194.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 75.0, 78.0, 74.0, 76.0, 72.0, 71.0, 76.0, 74.0], "value": 75.3, "unit": "score"}]}]} 16:59:26 INFO - Return code: 0 16:59:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:59:26 INFO - # TBPL SUCCESS # 16:59:26 INFO - Running post-action listener: _resource_record_post_action 16:59:26 INFO - [mozharness: 2017-05-15 23:59:26.466812Z] Finished run-tests step (success) 16:59:26 INFO - Running post-run listener: _resource_record_post_run 16:59:26 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393830400; Read time: 24; Write time: 365764 16:59:26 INFO - TinderboxPrint: CPU usage
13.3% 16:59:26 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 24 16:59:26 INFO - TinderboxPrint: I/O write bytes / time
393,830,400 / 365,764 16:59:26 INFO - TinderboxPrint: CPU idle
8,297.0 (86.7%) 16:59:26 INFO - TinderboxPrint: CPU user
1,215.5 (12.7%) 16:59:26 INFO - TinderboxPrint: Swap in / out
0 / 0 16:59:26 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114126848; Read time: 0; Write time: 130208 16:59:26 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 279703552; Read time: 0; Write time: 235556 16:59:27 INFO - Running post-run listener: _upload_blobber_files 16:59:27 INFO - Blob upload gear active. 16:59:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:59:27 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 16:59:27 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'] 16:59:27 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 16:59:27 INFO - (blobuploader) - INFO - Open directory for files ... 16:59:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:59:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:59:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:28 INFO - (blobuploader) - INFO - Done attempting. 16:59:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:59:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:59:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:29 INFO - (blobuploader) - INFO - Done attempting. 16:59:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:59:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:59:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:30 INFO - (blobuploader) - INFO - Done attempting. 16:59:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:59:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:59:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:34 INFO - (blobuploader) - INFO - Done attempting. 16:59:34 INFO - (blobuploader) - INFO - Iteration through files over. 16:59:34 INFO - Return code: 0 16:59:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:59:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:59:34 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30cb15a53e36a18ac0623e16064f42f8150504fa2abaaf2ffc9e4c2e585d8a91e0e2aeec9863e75b9bfd28434d754234b09f0d29151d12e2586c7acb1e063df8", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbbc8adc7f0f8da3b24d84aa1a93ad2d2bdd9b245654b97d2814a912df676077b97a4a500d954752cde887eb14c8be0a03b0f439aea762e4a1e6a593a9c5ddcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0be663eef6d0fe9af7ef65f203f2fa1c46c29d46bb407b8b85877ad0211a41c78aed6b7ea4aa43ef2679af8e31a2429b06bfb0cd64baa40b3b8a8bd6bc67015", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1e63a931639f5bb85962508b3712895239c90fe92cf5f91cf63c03785e1483dadc501d6479a744a2161b1e95420d496b75ddba056f917d20adaf22ffaa04a0e"} 16:59:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:59:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:59:34 INFO - Contents: 16:59:34 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30cb15a53e36a18ac0623e16064f42f8150504fa2abaaf2ffc9e4c2e585d8a91e0e2aeec9863e75b9bfd28434d754234b09f0d29151d12e2586c7acb1e063df8", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbbc8adc7f0f8da3b24d84aa1a93ad2d2bdd9b245654b97d2814a912df676077b97a4a500d954752cde887eb14c8be0a03b0f439aea762e4a1e6a593a9c5ddcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0be663eef6d0fe9af7ef65f203f2fa1c46c29d46bb407b8b85877ad0211a41c78aed6b7ea4aa43ef2679af8e31a2429b06bfb0cd64baa40b3b8a8bd6bc67015", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1e63a931639f5bb85962508b3712895239c90fe92cf5f91cf63c03785e1483dadc501d6479a744a2161b1e95420d496b75ddba056f917d20adaf22ffaa04a0e"} 16:59:34 INFO - Running post-run listener: copy_logs_to_upload_dir 16:59:34 INFO - Copying logs to upload dir... 16:59:34 INFO - mkdir: /builds/slave/test/build/upload/logs 16:59:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.003796 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-05-15 16:59:34.923818) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 16:59:34.927418) ========= 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=7de08804244eb36560b44971000001c3-1494891140.6873-252914416 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/30cb15a53e36a18ac0623e16064f42f8150504fa2abaaf2ffc9e4c2e585d8a91e0e2aeec9863e75b9bfd28434d754234b09f0d29151d12e2586c7acb1e063df8", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbbc8adc7f0f8da3b24d84aa1a93ad2d2bdd9b245654b97d2814a912df676077b97a4a500d954752cde887eb14c8be0a03b0f439aea762e4a1e6a593a9c5ddcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0be663eef6d0fe9af7ef65f203f2fa1c46c29d46bb407b8b85877ad0211a41c78aed6b7ea4aa43ef2679af8e31a2429b06bfb0cd64baa40b3b8a8bd6bc67015", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1e63a931639f5bb85962508b3712895239c90fe92cf5f91cf63c03785e1483dadc501d6479a744a2161b1e95420d496b75ddba056f917d20adaf22ffaa04a0e"} build_url:https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011419 build_url: 'https://queue.taskcluster.net/v1/task/YP_-BW02Rby3vzJ-SvVoRQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30cb15a53e36a18ac0623e16064f42f8150504fa2abaaf2ffc9e4c2e585d8a91e0e2aeec9863e75b9bfd28434d754234b09f0d29151d12e2586c7acb1e063df8", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbbc8adc7f0f8da3b24d84aa1a93ad2d2bdd9b245654b97d2814a912df676077b97a4a500d954752cde887eb14c8be0a03b0f439aea762e4a1e6a593a9c5ddcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0be663eef6d0fe9af7ef65f203f2fa1c46c29d46bb407b8b85877ad0211a41c78aed6b7ea4aa43ef2679af8e31a2429b06bfb0cd64baa40b3b8a8bd6bc67015", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1e63a931639f5bb85962508b3712895239c90fe92cf5f91cf63c03785e1483dadc501d6479a744a2161b1e95420d496b75ddba056f917d20adaf22ffaa04a0e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 16:59:34.960152) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 16:59:34.960444) ========= 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=7de08804244eb36560b44971000001c3-1494891140.6873-252914416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004673 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 16:59:35.041070) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 16:59:35.041375) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 16:59:35.044679) ========= ========= Total master_lag: 0.23 =========