builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-088 starttime: 1494607611.23 results: success (0) revision: 1e2fe13035e13b7b4001ade3b48f226957cef5fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:51.234511) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:51.234922) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:51.235446) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:51.261427) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:51.261691) ========= 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=9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-12 09:46:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2017-05-12 09:46:51 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.225904 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:51.502043) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:51.502368) ========= 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=9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020584 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:51.552549) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 09:46:51.552893) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1e2fe13035e13b7b4001ade3b48f226957cef5fc --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 1e2fe13035e13b7b4001ade3b48f226957cef5fc --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=9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-12 09:46:51,716 truncating revision to first 12 chars 2017-05-12 09:46:51,716 Setting DEBUG logging. 2017-05-12 09:46:51,716 attempt 1/10 2017-05-12 09:46:51,717 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1e2fe13035e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 09:46:52,829 unpacking tar archive at: mozilla-central-1e2fe13035e1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.414859 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 09:46:52.981382) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:52.981717) ========= script_repo_revision: 1e2fe13035e13b7b4001ade3b48f226957cef5fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:52.982084) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:52.982411) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 09:46:52.991846) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-12 09:46:52.992211) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:46:53 INFO - MultiFileLogger online at 20170512 09:46:53 in /builds/slave/test 09:46:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:46:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:46:53 INFO - {'append_to_log': False, 09:46:53 INFO - 'base_work_dir': '/builds/slave/test', 09:46:53 INFO - 'blob_upload_branch': 'Firefox', 09:46:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:46:53 INFO - 'branch': 'Firefox', 09:46:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:46:53 INFO - 'config_files': ('talos/linux_config.py',), 09:46:53 INFO - 'default_actions': ('clobber', 09:46:53 INFO - 'read-buildbot-config', 09:46:53 INFO - 'download-and-extract', 09:46:53 INFO - 'populate-webroot', 09:46:53 INFO - 'create-virtualenv', 09:46:53 INFO - 'install', 09:46:53 INFO - 'run-tests'), 09:46:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:46:53 INFO - 'download_minidump_stackwalk': True, 09:46:53 INFO - 'download_symbols': 'ondemand', 09:46:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:46:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:46:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:46:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:46:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:46:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:46:53 INFO - 'gecko_profile': False, 09:46:53 INFO - 'gecko_profile_interval': 0, 09:46:53 INFO - 'installer_path': 'installer.exe', 09:46:53 INFO - 'log_level': 'info', 09:46:53 INFO - 'log_name': 'talos', 09:46:53 INFO - 'log_to_console': True, 09:46:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:46:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:46:53 INFO - 'opt_config_files': (), 09:46:53 INFO - 'pip_index': False, 09:46:53 INFO - 'suite': 'dromaeojs', 09:46:53 INFO - 'system_bits': '32', 09:46:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:46:53 INFO - 'title': 'talos-linux64-ix-088', 09:46:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:46:53 INFO - 'use_talos_json': True, 09:46:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:46:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:46:53 INFO - 'work_dir': 'build'} 09:46:53 INFO - [mozharness: 2017-05-12 16:46:53.256908Z] Running clobber step. 09:46:53 INFO - Running pre-action listener: _resource_record_pre_action 09:46:53 INFO - Running main action method: clobber 09:46:53 INFO - rmtree: /builds/slave/test/build 09:46:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:46:54 INFO - Running post-action listener: _resource_record_post_action 09:46:54 INFO - [mozharness: 2017-05-12 16:46:54.275934Z] Finished clobber step (success) 09:46:54 INFO - [mozharness: 2017-05-12 16:46:54.276035Z] Running read-buildbot-config step. 09:46:54 INFO - Running pre-action listener: _resource_record_pre_action 09:46:54 INFO - Running main action method: read_buildbot_config 09:46:54 INFO - Using buildbot properties: 09:46:54 INFO - { 09:46:54 INFO - "project": "", 09:46:54 INFO - "product": "firefox", 09:46:54 INFO - "who": "ryanvm@gmail.com", 09:46:54 INFO - "installer_path": "public/build/target.tar.bz2", 09:46:54 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:46:54 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:46:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:46:54 INFO - "stage_platform": "linux64-pgo", 09:46:54 INFO - "basedir": "/builds/slave/test", 09:46:54 INFO - "buildnumber": 182, 09:46:54 INFO - "platform": "ubuntu64_hw", 09:46:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:46:54 INFO - "slavebuilddir": "test", 09:46:54 INFO - "taskId": "akeqhWl8RyC7G0IsDMgNYg", 09:46:54 INFO - "branch": "mozilla-central", 09:46:54 INFO - "script_repo_revision": "production", 09:46:54 INFO - "revision": "1e2fe13035e13b7b4001ade3b48f226957cef5fc", 09:46:54 INFO - "slavename": "talos-linux64-ix-088", 09:46:54 INFO - "repo_path": "mozilla-central" 09:46:54 INFO - } 09:46:54 INFO - Finding installer, test and symbols from parent task. 09:46:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/akeqhWl8RyC7G0IsDMgNYg'}, attempt #1 09:46:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/akeqhWl8RyC7G0IsDMgNYg'}, attempt #1 09:46:55 INFO - Task dependencies: LSGaJGHfTrmZDJ2dFRsIjA 09:46:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA'}, attempt #1 09:46:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.tar.bz2 09:46:55 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/test_packages.json 09:46:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.crashreporter-symbols.zip 09:46:55 INFO - Running post-action listener: _resource_record_post_action 09:46:55 INFO - [mozharness: 2017-05-12 16:46:55.662445Z] Finished read-buildbot-config step (success) 09:46:55 INFO - [mozharness: 2017-05-12 16:46:55.662663Z] Running download-and-extract step. 09:46:55 INFO - Running pre-action listener: _resource_record_pre_action 09:46:55 INFO - Running main action method: download_and_extract 09:46:55 INFO - mkdir: /builds/slave/test/build/tests 09:46:55 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')]} 09:46:55 INFO - trying https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.test_packages.json 09:46:55 INFO - Downloading https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:46:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:46:56 INFO - Downloaded 986 bytes. 09:46:56 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:46:56 INFO - Using the following test package requirements: 09:46:56 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:46:56 INFO - u'common': [u'target.common.tests.zip'], 09:46:56 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:46:56 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:46:56 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:46:56 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:46:56 INFO - u'mozbase': [u'target.common.tests.zip'], 09:46:56 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:46:56 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:46:56 INFO - u'web-platform': [u'target.common.tests.zip', 09:46:56 INFO - u'target.web-platform.tests.zip'], 09:46:56 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:46:56 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:46:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.common.tests.zip 09:46:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:46:56 INFO - Fetch https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.common.tests.zip into memory 09:46:57 INFO - Content-Length response header: 64595571 09:46:57 INFO - Bytes received: 64595571 09:47:08 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:47:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.common.tests.zip 09:47:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:47:08 INFO - Fetch https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.common.tests.zip into memory 09:47:09 INFO - Content-Length response header: 64595571 09:47:09 INFO - Bytes received: 64595571 09:47:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.talos.tests.zip 09:47:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:47:20 INFO - Fetch https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.talos.tests.zip into memory 09:47:21 INFO - Content-Length response header: 14409411 09:47:21 INFO - Bytes received: 14409411 09:47:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:21 INFO - trying https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.tar.bz2 09:47:21 INFO - Downloading https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:47:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:47:23 INFO - Downloaded 68686715 bytes. 09:47:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.tar.bz2 09:47:23 INFO - mkdir: /builds/slave/test/properties 09:47:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:47:23 INFO - Writing to file /builds/slave/test/properties/build_url 09:47:23 INFO - Contents: 09:47:23 INFO - build_url:https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.tar.bz2 09:47:23 INFO - Running post-action listener: _resource_record_post_action 09:47:23 INFO - Running post-action listener: set_extra_try_arguments 09:47:23 INFO - [mozharness: 2017-05-12 16:47:23.581173Z] Finished download-and-extract step (success) 09:47:23 INFO - [mozharness: 2017-05-12 16:47:23.581271Z] Running populate-webroot step. 09:47:23 INFO - Running pre-action listener: _resource_record_pre_action 09:47:23 INFO - Running main action method: populate_webroot 09:47:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:47:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:47:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:47:23 INFO - '--tppagecycles', 09:47:23 INFO - '7'], 09:47:23 INFO - 'tests': ['tsvgm']}}, 09:47:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:47:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:47:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:47:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:47:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:47:23 INFO - 'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:47:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:47:23 INFO - 'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['damp', 'tps']}, 09:47:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:47:23 INFO - 'tests': ['damp', 'tps']}, 09:47:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['dromaeo_dom']}, 09:47:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:47:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:47:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:47:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['a11yr', 09:47:23 INFO - 'ts_paint', 09:47:23 INFO - 'tpaint', 09:47:23 INFO - 'sessionrestore', 09:47:23 INFO - 'sessionrestore_no_auto_restore', 09:47:23 INFO - 'tabpaint']}, 09:47:23 INFO - 'other-e10s': {'tests': ['a11yr', 09:47:23 INFO - 'ts_paint', 09:47:23 INFO - 'tpaint', 09:47:23 INFO - 'sessionrestore', 09:47:23 INFO - 'sessionrestore_no_auto_restore', 09:47:23 INFO - 'tabpaint']}, 09:47:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:47:23 INFO - 'ts_paint', 09:47:23 INFO - 'tpaint', 09:47:23 INFO - 'sessionrestore', 09:47:23 INFO - 'sessionrestore_no_auto_restore', 09:47:23 INFO - 'tabpaint']}, 09:47:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:47:23 INFO - 'ts_paint', 09:47:23 INFO - 'tpaint', 09:47:23 INFO - 'sessionrestore', 09:47:23 INFO - 'sessionrestore_no_auto_restore', 09:47:23 INFO - 'tabpaint']}, 09:47:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['a11yr', 09:47:23 INFO - 'ts_paint', 09:47:23 INFO - 'tpaint', 09:47:23 INFO - 'sessionrestore', 09:47:23 INFO - 'sessionrestore_no_auto_restore', 09:47:23 INFO - 'tabpaint']}, 09:47:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['a11yr', 09:47:23 INFO - 'ts_paint', 09:47:23 INFO - 'tpaint', 09:47:23 INFO - 'sessionrestore', 09:47:23 INFO - 'sessionrestore_no_auto_restore', 09:47:23 INFO - 'tabpaint']}, 09:47:23 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:47:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:47:23 INFO - 'bloom_basic_ref']}, 09:47:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['tsvgx', 09:47:23 INFO - 'tsvgr_opacity', 09:47:23 INFO - 'tart', 09:47:23 INFO - 'tscrollx', 09:47:23 INFO - 'cart', 09:47:23 INFO - 'tsvg_static']}, 09:47:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:47:23 INFO - 'tsvgr_opacity', 09:47:23 INFO - 'tart', 09:47:23 INFO - 'tscrollx', 09:47:23 INFO - 'cart', 09:47:23 INFO - 'tsvg_static']}, 09:47:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:47:23 INFO - 'talos_options': ['--disable-e10s'], 09:47:23 INFO - 'tests': ['tp5o']}, 09:47:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:47:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:47:23 INFO - 'talos_options': ['--disable-e10s', 09:47:23 INFO - '--xperf_path', 09:47:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:23 INFO - 'tests': ['tp5n']}, 09:47:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:47:23 INFO - 'talos_options': ['--xperf_path', 09:47:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:23 INFO - 'tests': ['tp5n']}}, 09:47:23 INFO - 'talos.zip': {'path': '', 09:47:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:47:23 INFO - Running post-action listener: _resource_record_post_action 09:47:23 INFO - [mozharness: 2017-05-12 16:47:23.595034Z] Finished populate-webroot step (success) 09:47:23 INFO - [mozharness: 2017-05-12 16:47:23.595123Z] Running create-virtualenv step. 09:47:23 INFO - Running pre-action listener: _resource_record_pre_action 09:47:23 INFO - Running main action method: create_virtualenv 09:47:23 INFO - Creating virtualenv /builds/slave/test/build/venv 09:47:23 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 09:47:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:47:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:47:23 INFO - Using real prefix '/usr' 09:47:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:47:24 INFO - Installing distribute.............................................................................................................................................................................................done. 09:47:26 INFO - Installing pip.................done. 09:47:26 INFO - Return code: 0 09:47:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:47:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:47:26 INFO - Reading from file tmpfile_stdout 09:47:26 INFO - Output received: 09:47:26 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) 09:47:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:47: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')]} 09:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47: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')]} 09:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf51770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, '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': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', '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 09:47: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', 'pip>=1.5'] in /builds/slave/test/build 09:47: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 pip>=1.5 09:47:26 INFO - Using env: {'DISPLAY': ':0', 09:47:26 INFO - 'HOME': '/home/cltbld', 09:47:26 INFO - 'LANG': 'en_US.UTF-8', 09:47:26 INFO - 'LANGUAGE': 'en_US:en', 09:47:26 INFO - 'LOGNAME': 'cltbld', 09:47:26 INFO - 'MAIL': '/var/mail/cltbld', 09:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:26 INFO - 'MOZ_NO_REMOTE': '1', 09:47:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:26 INFO - 'NO_EM_RESTART': '1', 09:47:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:26 INFO - 'PWD': '/builds/slave/test', 09:47:26 INFO - 'SHELL': '/bin/bash', 09:47:26 INFO - 'SHLVL': '1', 09:47:26 INFO - 'TERM': 'linux', 09:47:26 INFO - 'TMOUT': '86400', 09:47:26 INFO - 'USER': 'cltbld', 09:47:26 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:26 INFO - '_': '/tools/buildbot/bin/python'} 09:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:26 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 09:47:26 INFO - Cleaning up... 09:47:26 INFO - Return code: 0 09:47:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:47: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')]} 09:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47: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')]} 09:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47: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', '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 0xbdf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf51770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, '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': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', '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 09:47: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:47: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 psutil>=3.1.1 09:47:26 INFO - Using env: {'DISPLAY': ':0', 09:47:26 INFO - 'HOME': '/home/cltbld', 09:47:26 INFO - 'LANG': 'en_US.UTF-8', 09:47:26 INFO - 'LANGUAGE': 'en_US:en', 09:47:26 INFO - 'LOGNAME': 'cltbld', 09:47:26 INFO - 'MAIL': '/var/mail/cltbld', 09:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:26 INFO - 'MOZ_NO_REMOTE': '1', 09:47:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:26 INFO - 'NO_EM_RESTART': '1', 09:47:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:26 INFO - 'PWD': '/builds/slave/test', 09:47:26 INFO - 'SHELL': '/bin/bash', 09:47:26 INFO - 'SHLVL': '1', 09:47:26 INFO - 'TERM': 'linux', 09:47:26 INFO - 'TMOUT': '86400', 09:47:26 INFO - 'USER': 'cltbld', 09:47:26 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:26 INFO - '_': '/tools/buildbot/bin/python'} 09:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:26 INFO - Downloading/unpacking psutil>=3.1.1 09:47:26 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 09:47:26 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 09:47:26 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 09:47:26 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 09:47:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:47:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:27 INFO - Installing collected packages: psutil 09:47:27 INFO - Running setup.py install for psutil 09:47:28 INFO - building 'psutil._psutil_linux' extension 09:47:28 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 09:47:28 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 09:47:28 INFO - building 'psutil._psutil_posix' extension 09:47:28 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 09:47:28 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 09:47:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:28 INFO - Successfully installed psutil 09:47:28 INFO - Cleaning up... 09:47:28 INFO - Return code: 0 09:47:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:47:28 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')]} 09:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:28 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')]} 09:47:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:28 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 0xbdf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf51770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, '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': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', '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 09:47:28 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 09:47:28 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 09:47:28 INFO - Using env: {'DISPLAY': ':0', 09:47:28 INFO - 'HOME': '/home/cltbld', 09:47:28 INFO - 'LANG': 'en_US.UTF-8', 09:47:28 INFO - 'LANGUAGE': 'en_US:en', 09:47:28 INFO - 'LOGNAME': 'cltbld', 09:47:28 INFO - 'MAIL': '/var/mail/cltbld', 09:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:28 INFO - 'MOZ_NO_REMOTE': '1', 09:47:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:28 INFO - 'NO_EM_RESTART': '1', 09:47:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:28 INFO - 'PWD': '/builds/slave/test', 09:47:28 INFO - 'SHELL': '/bin/bash', 09:47:28 INFO - 'SHLVL': '1', 09:47:28 INFO - 'TERM': 'linux', 09:47:28 INFO - 'TMOUT': '86400', 09:47:28 INFO - 'USER': 'cltbld', 09:47:28 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:28 INFO - '_': '/tools/buildbot/bin/python'} 09:47:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:47:28 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 09:47:28 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 09:47:28 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 09:47:28 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 09:47:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:47:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:47:29 INFO - Installing collected packages: mozsystemmonitor 09:47:29 INFO - Running setup.py install for mozsystemmonitor 09:47:29 INFO - Successfully installed mozsystemmonitor 09:47:29 INFO - Cleaning up... 09:47:30 INFO - Return code: 0 09:47:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:47:30 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')]} 09:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:30 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')]} 09:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:30 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 0xbdf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf51770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, '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': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', '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 09:47:30 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 09:47:30 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 09:47:30 INFO - Using env: {'DISPLAY': ':0', 09:47:30 INFO - 'HOME': '/home/cltbld', 09:47:30 INFO - 'LANG': 'en_US.UTF-8', 09:47:30 INFO - 'LANGUAGE': 'en_US:en', 09:47:30 INFO - 'LOGNAME': 'cltbld', 09:47:30 INFO - 'MAIL': '/var/mail/cltbld', 09:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:30 INFO - 'MOZ_NO_REMOTE': '1', 09:47:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:30 INFO - 'NO_EM_RESTART': '1', 09:47:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:30 INFO - 'PWD': '/builds/slave/test', 09:47:30 INFO - 'SHELL': '/bin/bash', 09:47:30 INFO - 'SHLVL': '1', 09:47:30 INFO - 'TERM': 'linux', 09:47:30 INFO - 'TMOUT': '86400', 09:47:30 INFO - 'USER': 'cltbld', 09:47:30 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:30 INFO - '_': '/tools/buildbot/bin/python'} 09:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:30 INFO - Downloading/unpacking jsonschema==2.5.1 09:47:30 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 09:47:30 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 09:47:30 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 09:47:30 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 09:47:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:47:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:47:31 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 09:47:31 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 09:47:31 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 09:47:31 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 09:47:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:47:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:47:31 INFO - warning: no files found matching '*.txt' 09:47:31 INFO - no previously-included directories found matching 'build' 09:47:31 INFO - no previously-included directories found matching 'dist' 09:47:31 INFO - no previously-included directories found matching '.git*' 09:47:31 INFO - Installing collected packages: jsonschema, functools32 09:47:31 INFO - Running setup.py install for functools32 09:47:31 INFO - warning: no files found matching '*.txt' 09:47:31 INFO - no previously-included directories found matching 'build' 09:47:31 INFO - no previously-included directories found matching 'dist' 09:47:31 INFO - no previously-included directories found matching '.git*' 09:47:31 INFO - Successfully installed jsonschema functools32 09:47:31 INFO - Cleaning up... 09:47:31 INFO - Return code: 0 09:47:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:31 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 0xbdf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf51770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, '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': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', '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 09:47:31 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 09:47:31 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 09:47:31 INFO - Using env: {'DISPLAY': ':0', 09:47:31 INFO - 'HOME': '/home/cltbld', 09:47:31 INFO - 'LANG': 'en_US.UTF-8', 09:47:31 INFO - 'LANGUAGE': 'en_US:en', 09:47:31 INFO - 'LOGNAME': 'cltbld', 09:47:31 INFO - 'MAIL': '/var/mail/cltbld', 09:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:31 INFO - 'MOZ_NO_REMOTE': '1', 09:47:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:31 INFO - 'NO_EM_RESTART': '1', 09:47:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:31 INFO - 'PWD': '/builds/slave/test', 09:47:31 INFO - 'SHELL': '/bin/bash', 09:47:31 INFO - 'SHLVL': '1', 09:47:31 INFO - 'TERM': 'linux', 09:47:31 INFO - 'TMOUT': '86400', 09:47:31 INFO - 'USER': 'cltbld', 09:47:31 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:31 INFO - '_': '/tools/buildbot/bin/python'} 09:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:47:31 INFO - Cleaning up... 09:47:31 INFO - Return code: 0 09:47:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:31 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 0xbdf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf51770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, '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': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', '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 09:47:31 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 09:47:31 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 09:47:31 INFO - Using env: {'DISPLAY': ':0', 09:47:31 INFO - 'HOME': '/home/cltbld', 09:47:31 INFO - 'LANG': 'en_US.UTF-8', 09:47:31 INFO - 'LANGUAGE': 'en_US:en', 09:47:31 INFO - 'LOGNAME': 'cltbld', 09:47:31 INFO - 'MAIL': '/var/mail/cltbld', 09:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:31 INFO - 'MOZ_NO_REMOTE': '1', 09:47:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:31 INFO - 'NO_EM_RESTART': '1', 09:47:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:31 INFO - 'PWD': '/builds/slave/test', 09:47:31 INFO - 'SHELL': '/bin/bash', 09:47:31 INFO - 'SHLVL': '1', 09:47:31 INFO - 'TERM': 'linux', 09:47:31 INFO - 'TMOUT': '86400', 09:47:31 INFO - 'USER': 'cltbld', 09:47:31 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:31 INFO - '_': '/tools/buildbot/bin/python'} 09:47:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:32 INFO - Downloading/unpacking blobuploader==1.2.4 09:47:32 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 09:47:32 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 09:47:32 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 09:47:32 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 09:47:33 INFO - Downloading blobuploader-1.2.4.tar.gz 09:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:47:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:47:33 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 09:47:33 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 09:47:33 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 09:47:33 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 09:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:47:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:47:33 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 09:47:33 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 09:47:33 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 09:47:33 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 09:47:33 INFO - Downloading docopt-0.6.1.tar.gz 09:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:47:34 INFO - Installing collected packages: blobuploader, requests, docopt 09:47:34 INFO - Running setup.py install for blobuploader 09:47:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:47:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:47:34 INFO - Running setup.py install for requests 09:47:34 INFO - Running setup.py install for docopt 09:47:34 INFO - Successfully installed blobuploader requests docopt 09:47:34 INFO - Cleaning up... 09:47:34 INFO - Return code: 0 09:47:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:34 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 0xbdf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf51770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, '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': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', '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 09:47:34 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 09:47:34 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 09:47:34 INFO - Using env: {'DISPLAY': ':0', 09:47:34 INFO - 'HOME': '/home/cltbld', 09:47:34 INFO - 'LANG': 'en_US.UTF-8', 09:47:34 INFO - 'LANGUAGE': 'en_US:en', 09:47:34 INFO - 'LOGNAME': 'cltbld', 09:47:34 INFO - 'MAIL': '/var/mail/cltbld', 09:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:34 INFO - 'MOZ_NO_REMOTE': '1', 09:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:34 INFO - 'NO_EM_RESTART': '1', 09:47:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:34 INFO - 'PWD': '/builds/slave/test', 09:47:34 INFO - 'SHELL': '/bin/bash', 09:47:34 INFO - 'SHLVL': '1', 09:47:34 INFO - 'TERM': 'linux', 09:47:34 INFO - 'TMOUT': '86400', 09:47:34 INFO - 'USER': 'cltbld', 09:47:34 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:34 INFO - '_': '/tools/buildbot/bin/python'} 09:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:34 INFO - Running setup.py (path:/tmp/pip-8ZMCoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:34 INFO - Running setup.py (path:/tmp/pip-E9rwE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:34 INFO - Running setup.py (path:/tmp/pip-3Rv75z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:35 INFO - Running setup.py (path:/tmp/pip-8Ud2dG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:35 INFO - Running setup.py (path:/tmp/pip-ajyfNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:35 INFO - Running setup.py (path:/tmp/pip-cA8SZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:35 INFO - Running setup.py (path:/tmp/pip-DcUOuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:35 INFO - Running setup.py (path:/tmp/pip-LLcriB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:35 INFO - Running setup.py (path:/tmp/pip-g0rBLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:35 INFO - Running setup.py (path:/tmp/pip-LbsFlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:35 INFO - Running setup.py (path:/tmp/pip-FgSJfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:35 INFO - Running setup.py (path:/tmp/pip-1P7jr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:35 INFO - Running setup.py (path:/tmp/pip-BQ2gdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:35 INFO - Running setup.py (path:/tmp/pip-hhl8Oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:35 INFO - Running setup.py (path:/tmp/pip-63nIiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:35 INFO - Running setup.py (path:/tmp/pip-99pxhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:36 INFO - Running setup.py (path:/tmp/pip-Z27wYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:47:36 INFO - Running setup.py install for manifestparser 09:47:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:47:36 INFO - Running setup.py install for mozcrash 09:47:36 INFO - Running setup.py install for mozdebug 09:47:36 INFO - Running setup.py install for mozdevice 09:47:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:47:36 INFO - Running setup.py install for mozfile 09:47:36 INFO - Running setup.py install for mozhttpd 09:47:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:47:36 INFO - Running setup.py install for mozinfo 09:47:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:47:36 INFO - Running setup.py install for mozInstall 09:47:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:47:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:47:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:47:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:47:36 INFO - Running setup.py install for mozleak 09:47:36 INFO - Running setup.py install for mozlog 09:47:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:47:37 INFO - Running setup.py install for moznetwork 09:47:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:47:37 INFO - Running setup.py install for mozprocess 09:47:37 INFO - Running setup.py install for mozprofile 09:47:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:47:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:47:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:47:37 INFO - Running setup.py install for mozrunner 09:47:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:47:37 INFO - Running setup.py install for mozscreenshot 09:47:37 INFO - Running setup.py install for moztest 09:47:37 INFO - Running setup.py install for mozversion 09:47:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:47:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:47:37 INFO - Cleaning up... 09:47:37 INFO - Return code: 0 09:47:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:37 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 0xbdf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf51770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, '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': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', '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 09:47:37 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 09:47:37 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 09:47:37 INFO - Using env: {'DISPLAY': ':0', 09:47:37 INFO - 'HOME': '/home/cltbld', 09:47:37 INFO - 'LANG': 'en_US.UTF-8', 09:47:37 INFO - 'LANGUAGE': 'en_US:en', 09:47:37 INFO - 'LOGNAME': 'cltbld', 09:47:37 INFO - 'MAIL': '/var/mail/cltbld', 09:47:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:37 INFO - 'MOZ_NO_REMOTE': '1', 09:47:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:37 INFO - 'NO_EM_RESTART': '1', 09:47:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:37 INFO - 'PWD': '/builds/slave/test', 09:47:37 INFO - 'SHELL': '/bin/bash', 09:47:37 INFO - 'SHLVL': '1', 09:47:37 INFO - 'TERM': 'linux', 09:47:37 INFO - 'TMOUT': '86400', 09:47:37 INFO - 'USER': 'cltbld', 09:47:37 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:37 INFO - '_': '/tools/buildbot/bin/python'} 09:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:38 INFO - Running setup.py (path:/tmp/pip-VM_Neh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:38 INFO - Running setup.py (path:/tmp/pip-vnXb6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:38 INFO - Running setup.py (path:/tmp/pip-IiYCbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:38 INFO - Running setup.py (path:/tmp/pip-bPgt7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:38 INFO - Running setup.py (path:/tmp/pip-QQD4nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:38 INFO - Running setup.py (path:/tmp/pip-3Zog7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:38 INFO - Running setup.py (path:/tmp/pip-HL3z0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:38 INFO - Running setup.py (path:/tmp/pip-XhGp3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:38 INFO - Running setup.py (path:/tmp/pip-lBP2I1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:38 INFO - Running setup.py (path:/tmp/pip-aWllg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:38 INFO - Running setup.py (path:/tmp/pip-9SdbZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:38 INFO - Running setup.py (path:/tmp/pip-uAJ1cE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:38 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)) 09:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:38 INFO - Running setup.py (path:/tmp/pip-7omSc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:39 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)) 09:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:39 INFO - Running setup.py (path:/tmp/pip-OLmAkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:39 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)) 09:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:39 INFO - Running setup.py (path:/tmp/pip-CxEtIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:39 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)) 09:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:39 INFO - Running setup.py (path:/tmp/pip-a96y9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:39 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)) 09:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:39 INFO - Running setup.py (path:/tmp/pip-tRpGDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:39 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)) 09:47:39 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)) 09:47:39 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)) 09:47:39 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)) 09:47:39 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)) 09:47:39 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)) 09:47:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:39 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 09:47:39 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 09:47:39 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 09:47:39 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 09:47:40 INFO - Downloading blessings-1.6.tar.gz 09:47:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:47:40 INFO - Installing collected packages: blessings 09:47:40 INFO - Running setup.py install for blessings 09:47:40 INFO - Successfully installed blessings 09:47:40 INFO - Cleaning up... 09:47:40 INFO - Return code: 0 09:47:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:47:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:40 INFO - Reading from file tmpfile_stdout 09:47:40 INFO - Current package versions: 09:47:40 INFO - argparse == 1.2.1 09:47:40 INFO - blessings == 1.6 09:47:40 INFO - blobuploader == 1.2.4 09:47:40 INFO - docopt == 0.6.1 09:47:40 INFO - functools32 == 3.2.3-2 09:47:40 INFO - jsonschema == 2.5.1 09:47:40 INFO - manifestparser == 1.1 09:47:40 INFO - mozInstall == 1.12 09:47:40 INFO - mozcrash == 1.0 09:47:40 INFO - mozdebug == 0.1 09:47:40 INFO - mozdevice == 0.50 09:47:40 INFO - mozfile == 1.2 09:47:40 INFO - mozhttpd == 0.7 09:47:40 INFO - mozinfo == 0.9 09:47:40 INFO - mozleak == 0.1 09:47:40 INFO - mozlog == 3.4 09:47:40 INFO - moznetwork == 0.27 09:47:40 INFO - mozprocess == 0.25 09:47:40 INFO - mozprofile == 0.28 09:47:40 INFO - mozrunner == 6.13 09:47:40 INFO - mozscreenshot == 0.1 09:47:40 INFO - mozsystemmonitor == 0.3 09:47:40 INFO - moztest == 0.8 09:47:40 INFO - mozversion == 1.4 09:47:40 INFO - psutil == 3.1.1 09:47:40 INFO - requests == 1.2.3 09:47:40 INFO - wsgiref == 0.1.2 09:47:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:40 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')]} 09:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:40 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')]} 09:47:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:40 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 0xbdf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf51770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, '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': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', '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 09:47:40 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 09:47:40 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 09:47:40 INFO - Using env: {'DISPLAY': ':0', 09:47:40 INFO - 'HOME': '/home/cltbld', 09:47:40 INFO - 'LANG': 'en_US.UTF-8', 09:47:40 INFO - 'LANGUAGE': 'en_US:en', 09:47:40 INFO - 'LOGNAME': 'cltbld', 09:47:40 INFO - 'MAIL': '/var/mail/cltbld', 09:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:40 INFO - 'MOZ_NO_REMOTE': '1', 09:47:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:40 INFO - 'NO_EM_RESTART': '1', 09:47:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:40 INFO - 'PWD': '/builds/slave/test', 09:47:40 INFO - 'SHELL': '/bin/bash', 09:47:40 INFO - 'SHLVL': '1', 09:47:40 INFO - 'TERM': 'linux', 09:47:40 INFO - 'TMOUT': '86400', 09:47:40 INFO - 'USER': 'cltbld', 09:47:40 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:40 INFO - '_': '/tools/buildbot/bin/python'} 09:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:41 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)) 09:47:41 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)) 09:47:41 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)) 09:47:41 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)) 09:47:41 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)) 09:47:41 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)) 09:47:41 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)) 09:47:41 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)) 09:47:41 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)) 09:47:41 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)) 09:47:41 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)) 09:47:41 INFO - Cleaning up... 09:47:41 INFO - Return code: 0 09:47:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:41 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 0xbdf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf51770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, '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': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', '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 09:47:41 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 09:47:41 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 09:47:41 INFO - Using env: {'DISPLAY': ':0', 09:47:41 INFO - 'HOME': '/home/cltbld', 09:47:41 INFO - 'LANG': 'en_US.UTF-8', 09:47:41 INFO - 'LANGUAGE': 'en_US:en', 09:47:41 INFO - 'LOGNAME': 'cltbld', 09:47:41 INFO - 'MAIL': '/var/mail/cltbld', 09:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:41 INFO - 'MOZ_NO_REMOTE': '1', 09:47:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:41 INFO - 'NO_EM_RESTART': '1', 09:47:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:41 INFO - 'PWD': '/builds/slave/test', 09:47:41 INFO - 'SHELL': '/bin/bash', 09:47:41 INFO - 'SHLVL': '1', 09:47:41 INFO - 'TERM': 'linux', 09:47:41 INFO - 'TMOUT': '86400', 09:47:41 INFO - 'USER': 'cltbld', 09:47:41 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:41 INFO - '_': '/tools/buildbot/bin/python'} 09:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:47:41 INFO - Cleaning up... 09:47:41 INFO - Return code: 0 09:47:41 INFO - Running post-action listener: _resource_record_post_action 09:47:41 INFO - Running post-action listener: _start_resource_monitoring 09:47:41 INFO - Starting resource monitoring. 09:47:41 INFO - [mozharness: 2017-05-12 16:47:41.369432Z] Finished create-virtualenv step (success) 09:47:41 INFO - [mozharness: 2017-05-12 16:47:41.369979Z] Running install step. 09:47:41 INFO - Running pre-action listener: _resource_record_pre_action 09:47:41 INFO - Running main action method: install 09:47:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:41 INFO - Reading from file tmpfile_stdout 09:47:41 INFO - Detecting whether we're running mozinstall >=1.0... 09:47:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:47:41 INFO - Reading from file tmpfile_stdout 09:47:41 INFO - Output received: 09:47:41 INFO - Usage: mozinstall [options] installer 09:47:41 INFO - Options: 09:47:41 INFO - -h, --help show this help message and exit 09:47:41 INFO - -d DEST, --destination=DEST 09:47:41 INFO - Directory to install application into. [default: 09:47:41 INFO - "/builds/slave/test"] 09:47:41 INFO - --app=APP Application being installed. [default: firefox] 09:47:41 INFO - mkdir: /builds/slave/test/build/application 09:47:41 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'] 09:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:47:58 INFO - Reading from file tmpfile_stdout 09:47:58 INFO - Output received: 09:47:58 INFO - /builds/slave/test/build/application/firefox/firefox 09:47:58 INFO - Running post-action listener: _resource_record_post_action 09:47:58 INFO - [mozharness: 2017-05-12 16:47:58.761004Z] Finished install step (success) 09:47:58 INFO - [mozharness: 2017-05-12 16:47:58.761252Z] Running run-tests step. 09:47:58 INFO - Running pre-action listener: _resource_record_pre_action 09:47:58 INFO - Running main action method: run_tests 09:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:47:58 INFO - Python 2.7.3 09:47:58 INFO - Return code: 0 09:47:58 INFO - grabbing minidump binary from tooltool 09:47:58 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')]} 09:47:58 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 0xf4ea30>, '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 0xf4f7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4fca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:47:58 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 09:47:58 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 09:47:58 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 09:47:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:47:58 INFO - Return code: 0 09:47:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:47:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:47:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:47:58 INFO - ENV: RUST_BACKTRACE is now 1 09:47:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:47:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-088 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:47:58 INFO - Using env: {'DISPLAY': ':0', 09:47:58 INFO - 'HOME': '/home/cltbld', 09:47:58 INFO - 'LANG': 'en_US.UTF-8', 09:47:58 INFO - 'LANGUAGE': 'en_US:en', 09:47:58 INFO - 'LOGNAME': 'cltbld', 09:47:58 INFO - 'MAIL': '/var/mail/cltbld', 09:47:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:47:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:58 INFO - 'MOZ_NO_REMOTE': '1', 09:47:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:47:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:58 INFO - 'NO_EM_RESTART': '1', 09:47:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:58 INFO - 'PWD': '/builds/slave/test', 09:47:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:47:58 INFO - 'RUST_BACKTRACE': '1', 09:47:58 INFO - 'SHELL': '/bin/bash', 09:47:58 INFO - 'SHLVL': '1', 09:47:58 INFO - 'TERM': 'linux', 09:47:58 INFO - 'TMOUT': '86400', 09:47:58 INFO - 'USER': 'cltbld', 09:47:58 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608', 09:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:58 INFO - '_': '/tools/buildbot/bin/python'} 09:47:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:47:59 INFO - mozversion application_buildid: 20170512154902 09:47:59 INFO - mozversion application_changeset: 1e2fe13035e13b7b4001ade3b48f226957cef5fc 09:47:59 INFO - mozversion application_display_name: Nightly 09:47:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:47:59 INFO - mozversion application_name: Firefox 09:47:59 INFO - mozversion application_remotingname: firefox 09:47:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:47:59 INFO - mozversion application_vendor: Mozilla 09:47:59 INFO - mozversion application_version: 55.0a1 09:47:59 INFO - mozversion platform_buildid: 20170512154902 09:47:59 INFO - mozversion platform_changeset: 1e2fe13035e13b7b4001ade3b48f226957cef5fc 09:47:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:47:59 INFO - mozversion platform_version: 55.0a1 09:47:59 INFO - using testdate: 1494607679 09:47:59 INFO - actual date: 1494607679 09:47:59 INFO - starting webserver on 'localhost:36343' 09:47:59 INFO - SUITE-START | Running 2 tests 09:47:59 INFO - TEST-START | dromaeo_css 09:47:59 INFO - operating with platform_type : linux_ 09:47:59 INFO - Initialising browser for dromaeo_css test... 09:47:59 INFO - TEST-INFO | started process 4947 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3eZQKd/profile http://localhost:36343/getInfo.html) 09:48:02 INFO - PID 4947 | __metrics Screen width/height:1600/1200 09:48:02 INFO - PID 4947 | colorDepth:24 09:48:02 INFO - PID 4947 | Browser inner width/height: 1024/695 09:48:02 INFO - PID 4947 | __metrics 09:48:03 INFO - PID 4947 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:48:03 INFO - PID 4947 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:48:04 INFO - TEST-INFO | 4947: exit 0 09:48:04 INFO - Browser initialized. 09:48:04 INFO - Running cycle 1/1 for dromaeo_css test... 09:48:04 INFO - TEST-INFO | started process 5044 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3eZQKd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:48:15 INFO - PID 5044 | RSS: Main: 148307968 09:48:15 INFO - PID 5044 | 09:51:18 INFO - PID 5044 | Cycle 1(1): loaded http://localhost:36343/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36343/tests/dromaeo/cssquery-ext.html) 09:51:20 INFO - PID 5044 | RSS: Main: 275648512 09:51:20 INFO - PID 5044 | 09:54:22 INFO - PID 5044 | Cycle 1(1): loaded http://localhost:36343/tests/dromaeo/cssquery-ext.html (next: http://localhost:36343/tests/dromaeo/cssquery-jquery.html) 09:54:22 INFO - PID 5044 | RSS: Main: 224993280 09:54:22 INFO - PID 5044 | 09:57:24 INFO - PID 5044 | Cycle 1(1): loaded http://localhost:36343/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36343/tests/dromaeo/cssquery-mootools.html) 09:57:24 INFO - PID 5044 | RSS: Main: 240807936 09:57:24 INFO - PID 5044 | 10:00:26 INFO - PID 5044 | Cycle 1(1): loaded http://localhost:36343/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36343/tests/dromaeo/cssquery-prototype.html) 10:00:27 INFO - PID 5044 | RSS: Main: 175218688 10:00:27 INFO - PID 5044 | 10:03:29 INFO - PID 5044 | Cycle 1(1): loaded http://localhost:36343/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36343/tests/dromaeo/cssquery-yui.html) 10:03:30 INFO - PID 5044 | RSS: Main: 178065408 10:03:30 INFO - PID 5044 | 10:06:32 INFO - PID 5044 | Cycle 1(1): loaded http://localhost:36343/tests/dromaeo/cssquery-yui.html (next: http://localhost:36343/tests/dromaeo/cssquery-dojo.html) 10:06:32 INFO - PID 5044 | RSS: Main: 265236480 10:06:32 INFO - PID 5044 | 10:06:32 INFO - PID 5044 | __start_tp_report 10:06:32 INFO - PID 5044 | _x_x_mozilla_page_load 10:06:32 INFO - PID 5044 | _x_x_mozilla_page_load_details 10:06:32 INFO - PID 5044 | |i|pagename|runs| 10:06:32 INFO - PID 5044 | |0;dojo.html;3298.63714991351;3324.4680851063836;3336.3160552086815;3343.682350176732;3345.8327083645827;5738.7991420300295;5763.394843541426;5815.38938411467;5826.737796799143;5847.970760146193;5812.302523697152;5830.3586605473365;5845.5031322337545;5889.087191485327;5965;4789;5054.772535235923;5134.204198349253;5187.144121219994;5259.921101183466;5230.6600070995455;5294.41761406245;5304.443033481487;5310.362756469229;5311.468853114697;5136.5377116059735;5176.042432150064;5202.037623039712;5222.32109825724;5235.5549778268705;5234.241035049935;5244.842654720364;5246.396664383607;5300.12547929595;5321.547668448186;5188.7146206958605;5212.4266330703595;5224.08578498765;5237.869053273661;5279.9968006078725;5738.311402631669;5770.394108618619;5846.356900740915;5880.764769409218;5886.852828679274;6208.596441231305;6304.810855674337;6313.652749098797;6320.367963203643;6323.988161894074;6409.519286053518;6420.678966051679;6423.807285781388;6429.742810287583;6430.871382572323;5897.882042359129;5958.6424814511265;5963.761449542013;5966.522678185735;5981.880362392728;6365.14070600463;6403.0715546246465;6424.453921416732;6509.576877503042;6555.639439830855;6076.8784624308155;6111.53932221086;6122.77432918503;6141.486007784275;6268.341824108403;6268.686565671698;6348.936510634834;6387;6388.041793730885;6392.520560957947;6341.365863413714;6344.016677415008;6344.016677415008;6345.17512723343;6354.777582784581;6145.108959200891;6210.285817130997;6237.064440333896;6320.905186422207;6355.428011478989;4752;4819.807207711722;4837.443693975168;4838.2016967200725;4855.975720121377;4259.893502662459;4271.316589345752;4274.7435153890865;4276.786160691953;4305;5926.881462370815;5927.644341339534;5950;5952.672603006789;5955.374685657944;6121.300552271563;6276.748930042747;6278.434940855345;6360.332165122688;6372.139761132188;5938.970305148447;5955.404459554096;5958.076498142795;5959.374265702126;5961.254843144607;5402.35171779389;5402.51377376038;5406.243125962368;5406.297181366476;5407.513323800877;6349.460295874902;6512.283648798533;6525.967370163119;6630.602163870183;6644.103046088716;6375.617462952052;6403.487720982217;6424.678766061772;6426.325235850355;6432.581882177644;6638.966805165944;6663.866722665616;6670.232923213893;6670.966645166937;6673.399394054559;6113.969430152999;6115.453310713017;6164.537659675452;6225.906611400742;6285.654289014148;6343.841403964938;6406.679666016774;6425.261094973951;6427.903581446376;6431.035344698333;6184.969075154595;6220.937790622036;6223.159873417212;6227.782027629011;6230.252369715663;6049.576529642883;6078.969605151946;6105.15650299495;6117.785877494266;6197.225346831646;6125.601746185282;6149.569530132848;6150.7847225346895;6164.445199932027;6173.84565385869;6141.539384546088;6171.691415429301;6198.814035578759;6207.16203312547;6208.534359923115;5761.827145185651;5776.768929242951;5843.707814609167;5980.910286345621;6033.909491357545 10:06:32 INFO - PID 5044 | |1;ext.html;730.9671064802084;790.3479629305823;809.3606051219537;1137.5961533655557;1156.1213477756903;28013.57979630307;36513.81743091287;37127.81436092819;37199.814000930026;37226.44160337597;37850.0537486563;37972.81013594928;38079.42880856789;38177.42733858994;38243.23513529728;5568.916466253009;5714.314282286111;7195.784126476214;7320.597367144805;7325.926740732578;7033.331833475837;7199.0281312022735;7200.279972002811;7204.135503739559;7204.603746793924;7207.063081799385;7284.890726639104;7287.890681639779;7288.8177795555;7324.340809327159;7226.747063852767;7291.598962057111;7300.707971681127;7308.780736577911;7314.670839812221;7129.287071292881;7199.0641216642025;7204.063471748639;7204.243554426815;7206.891896621555;34281.31437371264;34388.82805585956;34396.312073758636;34445.31109377823;34511;3557.2174121693447;3568.0901370150705;3598.442241452579;3600.261946301002;3604.711623070148;26964.191074267805;26986.92052317905;27033.729662703317;27064.729352706418;27066;3680.8159592020293;3687.078230442389;3687.1888184599347;3698.9445158322646;3706.684931780802;183789;184174;184641;184719;184910;45617;45642.54357456449;45667;45676.314855277196;45695.31457028147;47551.5244847554;47741.5225847744;47744.9416799569;47760.761196193795;47817;14463.638409039648;14607.050541714967;14704.852951470562;14737.852621473648;14762.852371476363;10710.625128120479;10899.455027248607;11215.66353009411;11943.940280298717;12011.63965081049;12695.587838310876;12863.807042894365;12866.935665321613;13013.479460821462;13076.084674072723;5204.609654275944;5216.070054079939;5245.527902488794;5290.550303224192;5372.946270537323;1035.1822060572215;1077.2082519348273;1088.678839742278;1095.3756358875364;1113.565709373345;24980.875095624404;25013.499730005296;25033.62449563258;25090.623640645405;25103.497930041296;1163.6218229075414;1170.6780635325354;1181.3502573584494;1193.7373777768878;1193.7612477504533;12502.687432814071;13330;13342.06605537603;13345.799813002814;13361.933190333986;225512;226279;226930;227050;227199;48860.267095993586;48980.26529602059;48995.26507102396;49033.509664903606;49161.50838491641;1442.5672298310465;1449.615851799274;1500.1599104501513;1505.4128889733013;1510.1543135843742;23518.529629406974;24673.136440224505;24876.751232487444;24926.127585534417;24971;37178;46569;47529.76235118802;47540.28689569728;47548.28677569908;191554;192166;192523;192793;193261;28556.286092847844;28684.13947581576;30904.53643195309;31117.8444107778;31221.687783121877;38072.238555228185;38134.42798357971;40141.79929100336;40354.394684079176;40533.18933621252;29858.701412986462;30464.238394040327;30467.542986854773;30594.847025764728;31158.376832463677;30052.398952021274;30309.69690303157;30530.389392212477;31334;31402.371952560363;34024.48963265504;35338.82330588331;35446.46830297496;35499.112522187155;35530.644693552735;29236.122916312546;29400.26499337534;29939;30111;30374.84812575923;23656.29031129069;24367.51264974726;24459.285155801972;24589;24672.876635616707 10:06:32 INFO - PID 5044 | |2;jquery.html;438.88808353869797;449.1264490565849;451.9435070616173;463.10620502430294;467.3970577954439;18013.549661258476;19500.609987800235;20271.39185824424;20353.694694579575;20372.286969956054;20702.378928632166;22060.917306529856;22542.77457225427;22713.091476340967;22931.312060638167;5310.362756469229;5486.341639003325;5535.280413546254;5551.333839939213;5576.888462230753;5447.291852059227;5492.203630473579;5683.374828768832;5713.971430142843;5722.170285308628;5675.5459563234845;5692.146178073302;5694.77220911163;5700.914486282709;5709.029464990961;5499.092649712793;5588.888222235553;5612.607117501777;5623.240862483553;5624.381318055031;4999.175136102539;5098;5106.974465127651;5116.974415127937;5125.077486052509;25089.87455062713;25744.8712756435;26207;26407.207783766327;26445;2154.138344662132;2175.98912005439;2197.5275315807016;2218.7448443429053;2221.466847956479;16703.248353824107;16837.24232409532;17421.47735567935;17440.738388924176;17572.91213543924;2079.5840831833543;2147.742270927483;2177.923772667965;2182.847200695952;2189.4526368407896;83911;103372.48313758458;103413.48293258561;103553;105044;12294.815577766341;12551.497940082294;12644.178128421807;12754.80867786984;12783;17559.297628094988;17631.911840440716;17649.7352539712;17746.91126544359;17849;8678.95660521706;8819.162179592928;8928.68749593774;8955;9032.774180645536;7961.203879612109;8015.519068855887;8032.208459199453;8205.58972051395;8212.65939207323;7973.122956474783;8103.351731861437;8117.391195660349;8265.586720663943;8289.917100828914;2258.254775923941;2275.485588424767;2281.9657705134437;2313.0747700919765;2406.5908795504806;998.32612986234;1037.1029059863165;1038.5378506564657;1046.1369370269558;1054.3989925742408;19176.71234931477;23785.405364866016;25909.35226619312;25913.352166195997;26185.869070654524;1059.6873922192972;1067.1782727299935;1069.9358038517714;1076.9299995500267;1109.8668159820872;11252.15608829341;11760;11825.349605771593;11912.821307680391;11919.761604767857;503597;508686;516794;521953;522632;89720.23324722187;95546;97226;97989.51005244929;101889.49055254825;1222.4999875255126;1255.428779905141;1271.2753730373784;1280.4558062823448;1283.2813624370383;17495.912520437316;17775.555611109827;18092.72860907061;18108.547286317946;18515.81484185195;46218.7689061566;46269.76865115787;46404;47109;47484.7625761869;56140.56009381384;58415;58844.4115558839;58881.578947368216;59568;9190.35667503295;9280.814383712423;9429.764255893657;9588.568514417007;10026.899731002888;13662.726745465234;14140.363683634056;14746.63133421673;14782.6304342388;15130.243487825785;12411.62765117048;12583.811242831182;12770.936145319214;12865.742685146057;12970.610881673565;11714.941425292818;11730.648080557597;11886.881131188577;12285;12374.195677281308;12356.876431235572;12986.48054077864;13123;13250.138740981805;13606.93196534011;9571.425714457155;9572.377795443273;9573.712788616353;9859.852102218329;10011.749706257402;12938.223706577259;12965.870341296466;12985;13181.354814207545;13204.339783011003 10:06:32 INFO - PID 5044 | |3;mootools.html;1375.1611516974647;1405.0796728143064;1423.736608727385;1428.5928510374545;1431.856814318568;11430.599929002476;11789.469473873674;11802.350870702108;11845.111616628767;11850.585229516968;11337.773244535125;11390.658280251606;11664;11788.646340609774;11806.409679516033;2321.3964369263986;2371.5019845832394;2379.5478859016816;2382.761723827612;2391.0435825669697;1982.2863769043133;2287.2223444029028;2360.9645855312183;2368.6328619064075;2370.087516306226;2052.5894821035777;2299.9655005174936;2369.277370402027;2370.2533701883935;2374.25211058516;2215.944601384962;2249.100359856054;2253.357793028987;2255.7293124825046;2255.909763609454;2252.7972482476493;2255.6052690779047;2266.7506574276817;2272.4546108933737;2301.6662583925404;10783.5686572537;10984.615538456115;10987;10989.230753847234;10989.835152472719;6511.316311787289;6755.7973260802255;6759.391654751096;6766.357196066366;6772.762953296611;3751.381022131345;4075.1442197138635;4100.5489396166395;4156.875293741193;4166.250074986501;1313.2186349122353;1338.1235290884485;1373.9519116830863;1429.5854202281325;1458.4749490183526;17516.38692645751;17552.297908083663;17579;17654.293828246853;17658.205380757896;5176.352955880515;5206.921896171561;5390.407055223922;5418.91871621926;5445.156000819879;12497.687557810945;12499.250044997329;12514.3742812859;12514.3742812859;12524.248545087325;6491.448226900767;6498.057781621638;6501.0898474213645;6501.2198536175965;6501.544891857619;5850.590458667937;6070.7875224366935;6099.3900609938655;6187.752489900444;6212.254529456494;4200.15996800635;4260.68044896634;4262.360645903139;4299.441072660534;4331.610155086058;1112.7051331397186;1127.4081107418672;1190.678516800459;1210.4552951171831;1225.3811825028304;709.5742554467381;836.8617683686056;854.6239654551978;864.6325311742485;875.2079368171815;5992.700364981821;6010.9699451502465;6011.729472173763;6041.969790151021;6257.4994000480765;930.7300882743929;930.8417569013284;956.4787190980932;960.70698436976;976.4092723902079;3748.5689145748042;3826.559945606291;3903.10228647407;3916.060145565044;3919.4300813532573;15073.397064117313;15339.846601533842;15420.151891646066;15472.303746331441;15492.30284637194;15193.164375959428;15214.619634509227;15218.315175817115;15242.161681107646;15261.389544418098;9305.627774889064;9536.189423898908;9657.613695452103;9717.80564388694;9729.416235025921;6708.765193218214;6916.273791251846;6988.685509151998;7030.3321184487395;7074.292570742885;13747.725045499234;13773.793393098911;13774.173549587056;14041.368138434;14077.718445630826;13810.516631917835;13908.304584770518;14040.613095773539;14068.648283792987;14148.957974035631;9573;9628.374155679858;9759.658411955548;9806.166475849348;9835.114839664464;11360.318380897173;11378.943105284421;11455.713607159887;11497.367644779617;11551.595694150665;9328.813423731623;9475.431474111576;9608.13526782593;9728.513574321114;9818.263630227619;9420.62317507292;9426.104520070583;9443.858342124737;9576.329656923948;9611.231101511723;8358.247757701836;8495.830083398174;8503.107173746668;8511.95744021276;8514.276286515715;5652.802151924828;5663.292088488939;5715.371309155907;5759.827205183851;5884.08796636522;5949.6430214187285;5971.970140149445;5976.163337132718;5991.610545314715;6165.5375846810775 10:06:32 INFO - PID 5044 | |4;prototype.html;400.27749911409927;428.4943766355701;430.1032347555347;431.9847909502171;436.3236982676851;5835.416458354162;5856.180134781134;5864.120381942711;5872.442117998794;5918.556108291879;5669.433056694328;5758.568107391952;5791.71041447928;5795.420457954203;5809.448102430262;4504.436945381827;4543.70465919715;4546.045330480603;4546.454425468948;4546.727196368212;4570.588647021767;4575.565321294472;4578.1301552705045;4594;4604.401427814396;4417.3815665806815;4495.415595972519;4547.363369128325;4555.362249285086;4568.47462541807;4518.277075667877;4518.593326600589;4532.501424843264;4534.684941367;4566.675664057419;4066.7356621819486;4085.0400155963325;4095.344744840841;4102.774347410891;4105.8973525661795;5527.198556209327;5630.493255607015;5736.56975726818;5738.713064346808;5745.138229265602;5296.179092240709;5365.731713414314;5377.6504527206;5379;5398.8920221595745;6130.846728831771;6154.753809847601;6164.660943648098;6169.598976066542;6182.752689892399;4199.601037901394;4314.3528470729325;4329.480462344507;4337.154254920292;4628.819650988976;6098.481629061535;6245.3442388549;6282.277538083134;6424.357564243538;6487.481001519867;4400.515943246241;4404.6256068233915;4413.735175889393;4414.359917811899;4419.845305414295;4272.6795490338345;4358.346248062816;4471.821127154943;4474.619657329098;4493.5281795411665;5351.143816989263;5431.809886654027;5475.808346707846;5485.259489968803;5489.862753431116;6120.877582448326;6155.415235552616;6175.814725558241;6180.381961803783;6220.813375598739;1682.6887025900248;1715.7426386041943;1734.17626627351;1735.1150913034191;1736.3575477073564;1236.814477828315;1262.3498898067503;1267.258653687603;1280.1678908709414;1284.0882973089024;5821.970890145522;5854.648721076664;5876.44173803488;5894.823155305347;5904.258243818084;6282.685865706696;6331;6331.841703957346;6338.904916426257;6340.397662222082;5664.5751568631695;5833.154192642043;5878.441548052928;5889.293284805765;5889.528837692974;3307.1732066983254;3336.51620515024;3346.983265083659;3356.1945133167865;3359.193793489545;6439.581427207217;6538.411542961157;6538.444232240217;6554.70503827329;6572.309907459744;6419.325970773095;6425.293217746044;6434.163558737428;6434.806955791334;6444.613323200623;6371.203599550056;6505.243584232231;6515.478761699147;6536.444402225769;6546.934530654633;6234.407731265523;6255.46828519581;6266.467350275278;6268.811935641938;6303.936960630335;5892.114523352486;5953.851153721192;6042.969785151046;6167.876642467215;6196.938030619636;6469.320721324193;6477.935220647733;6496.382843629847;6536.411722944958;6562.967185164234;4294.914101718011;4892.899097702988;4921.4832442593015;4930.161872481615;4937.851864444073;6295.213098362705;6332.796768613948;6341.302456729663;6352.237731472253;6356.555041147075;6166.167567378347;6167.290761562478;6188.597741146811;6192.938070619236;6200.410960958701;6054.606450580698;6136.325004249618;6169.907451388322;6171.907421388593;6178.845528861634;6066.028540724194;6152.907706384318;6169.784057557964;6175.2589689237575;6176.9073463897175;6034.8793024138395;6106.816795496142;6125;6134.325224225242;6162.661053642093;5994.250718660168;6004.708987567519;6009.956614751006;6023.819285421444;6043.667598282137 10:06:32 INFO - PID 5044 | |5;yui.html;823.3331001888467;847.940644154909;864.5072308783991;864.9481031138129;874.0865795243969;14266.358013889374;14969.5509134726;15102.773458398106;15215;15255.771163432557;15934.760978585331;16073.99999999997;16220.513384598451;16509.422170224043;18153.636927261447;962.4225464721163;979.5102448775613;990.10395591989;990.420603946692;990.7721224118457;697.0728930522394;994.6518718448558;998.3760149906309;998.6454808542971;998.9700308990706;765.9846803063936;974.9415035097882;1000.6397696829135;1009.9394036357805;1012.5696578953952;985.9950700246488;991.5686676295799;992.6823416506721;996.9551370188286;1000.4247557654378;737.0712901743788;737.4800765460337;738.1032046064058;741.7107328142029;742.2243755275696;16318.83681163185;16849.41027064059;17066.82933170665;17608.91195544014;17765.111744412858;18176.363827266116;18485.168167432497;18813;18867.905660471748;18955.526111847175;15099.471518496799;15307.311170997333;15324.394852875039;15505.534833955;15517.379304827906;3413.0784688134313;3423.8116903570294;3429.9657003430148;3430.0567343980356;3431.4681224410256;89917;89991.55004225002;90809;91159;91925;12316;12321.07591930591;12463.875361246453;12468.314242716557;12480.063995200215;21386.251481198393;21714.565708686056;21722.674159887614;21803.454913627287;21923.342299731034;7993.000874890638;8132.75601731949;8163.7142700005215;8174.182581741778;8177.141400152898;4314.460692413449;4361.694681372337;4393.29707246839;4398.64810815134;4401.185780630593;5028.321176641107;5128.820491282787;5193.11717008102;5199.11615025439;5200.583953283804;935.1910597333393;935.6023689931752;938.216589148069;942.8067246214509;946.4652471353663;684.4968947823346;693.0816667915033;707.7310621963621;708.9078419805393;736.9852602947911;11280.2667826591;12539.74920501603;12693.49226030967;12842.550510732079;13085.672858178621;698.4482259015423;708.8227943014247;735.6910097758954;743.605888878895;746.637880627895;5297.390800058042;5402.594805389612;5571.331440227199;5604.551635869121;5827.125931110282;75756.24243757493;76718;77267;78360;78739;64795.67602162054;65212.67393663001;66147.66926165434;66237;66333.33666663368;57500.42499575034;57833.132503012486;58232.7088364564;58654.706726466095;61692.383076169564;11468.770624587629;11568.710782230512;11702.35637039971;11818.822717659414;11949.10381721357;27297.676952993974;29384.853075734485;33636.66363336433;34653.82673086618;35756.10609734777;55110.72444637751;55577.72211138919;56343.43656563382;57201.71399142977;59556;15817.130057846929;15903.761443578587;15943.36226550925;16127.274272657523;16234.350625975301;18681.71977420367;18792.248310067444;18853;18920.33778817735;19025.809741902405;16217.35130594763;16294.511164665078;16319.83680163183;16550.255238514535;16631.417900373428;17251.482455526355;17503.562410939827;17590.38433654816;17618.82381176172;18040.819591803913;16636.83363166353;16689.582760431087;16720.74918876242;16785;16987.150642468074;11171.832422513831;11252.26860254081;11463.484143213409;11495.252808567751;11880.584179553674;12804.807927881275;13182.406791694206;13313.46746130144;13908.791368129689;14061.437542498186 10:06:32 INFO - PID 5044 | __end_tp_report 10:06:32 INFO - PID 5044 | __start_cc_report 10:06:32 INFO - PID 5044 | _x_x_mozilla_cycle_collect,1391 10:06:32 INFO - PID 5044 | __end_cc_report 10:06:32 INFO - PID 5044 | __startTimestamp1494608792686__endTimestamp 10:06:32 INFO - PID 5044 | 10:06:32 INFO - PID 5044 | ------- Summary: start ------- 10:06:32 INFO - PID 5044 | Number of tests: 6 10:06:32 INFO - PID 5044 | 10:06:32 INFO - PID 5044 | [#0] dojo.html Cycles:165 Average:5834.43 Median:6078.97 stddev:706.22 (11.6%) stddev-sans-first:679.79 10:06:32 INFO - PID 5044 | Values: 3298.6 3324.5 3336.3 3343.7 3345.8 5738.8 5763.4 5815.4 5826.7 5848.0 5812.3 5830.4 5845.5 5889.1 5965.0 4789.0 5054.8 5134.2 5187.1 5259.9 5230.7 5294.4 5304.4 5310.4 5311.5 5136.5 5176.0 5202.0 5222.3 5235.6 5234.2 5244.8 5246.4 5300.1 5321.5 5188.7 5212.4 5224.1 5237.9 5280.0 5738.3 5770.4 5846.4 5880.8 5886.9 6208.6 6304.8 6313.7 6320.4 6324.0 6409.5 6420.7 6423.8 6429.7 6430.9 5897.9 5958.6 5963.8 5966.5 5981.9 6365.1 6403.1 6424.5 6509.6 6555.6 6076.9 6111.5 6122.8 6141.5 6268.3 6268.7 6348.9 6387.0 6388.0 6392.5 6341.4 6344.0 6344.0 6345.2 6354.8 6145.1 6210.3 6237.1 6320.9 6355.4 4752.0 4819.8 4837.4 4838.2 4856.0 4259.9 4271.3 4274.7 4276.8 4305.0 5926.9 5927.6 5950.0 5952.7 5955.4 6121.3 6276.7 6278.4 6360.3 6372.1 5939.0 5955.4 5958.1 5959.4 5961.3 5402.4 5402.5 5406.2 5406.3 5407.5 6349.5 6512.3 6526.0 6630.6 6644.1 6375.6 6403.5 6424.7 6426.3 6432.6 6639.0 6663.9 6670.2 6671.0 6673.4 6114.0 6115.5 6164.5 6225.9 6285.7 6343.8 6406.7 6425.3 6427.9 6431.0 6185.0 6220.9 6223.2 6227.8 6230.3 6049.6 6079.0 6105.2 6117.8 6197.2 6125.6 6149.6 6150.8 6164.4 6173.8 6141.5 6171.7 6198.8 6207.2 6208.5 5761.8 5776.8 5843.7 5980.9 6033.9 10:06:32 INFO - PID 5044 | 10:06:32 INFO - PID 5044 | [#1] ext.html Cycles:180 Average:36124.14 Median:31277.84 stddev:52364.20 (167.4%) stddev-sans-first:52443.66 10:06:32 INFO - PID 5044 | Values: 731.0 790.3 809.4 1137.6 1156.1 28013.6 36513.8 37127.8 37199.8 37226.4 37850.1 37972.8 38079.4 38177.4 38243.2 5568.9 5714.3 7195.8 7320.6 7325.9 7033.3 7199.0 7200.3 7204.1 7204.6 7207.1 7284.9 7287.9 7288.8 7324.3 7226.7 7291.6 7300.7 7308.8 7314.7 7129.3 7199.1 7204.1 7204.2 7206.9 34281.3 34388.8 34396.3 34445.3 34511.0 3557.2 3568.1 3598.4 3600.3 3604.7 26964.2 26986.9 27033.7 27064.7 27066.0 3680.8 3687.1 3687.2 3698.9 3706.7 183789.0 184174.0 184641.0 184719.0 184910.0 45617.0 45642.5 45667.0 45676.3 45695.3 47551.5 47741.5 47744.9 47760.8 47817.0 14463.6 14607.1 14704.9 14737.9 14762.9 10710.6 10899.5 11215.7 11943.9 12011.6 12695.6 12863.8 12866.9 13013.5 13076.1 5204.6 5216.1 5245.5 5290.6 5372.9 1035.2 1077.2 1088.7 1095.4 1113.6 24980.9 25013.5 25033.6 25090.6 25103.5 1163.6 1170.7 1181.4 1193.7 1193.8 12502.7 13330.0 13342.1 13345.8 13361.9 225512.0 226279.0 226930.0 227050.0 227199.0 48860.3 48980.3 48995.3 49033.5 49161.5 1442.6 1449.6 1500.2 1505.4 1510.2 23518.5 24673.1 24876.8 24926.1 24971.0 37178.0 46569.0 47529.8 47540.3 47548.3 191554.0 192166.0 192523.0 192793.0 193261.0 28556.3 28684.1 30904.5 31117.8 31221.7 38072.2 38134.4 40141.8 40354.4 40533.2 29858.7 30464.2 30467.5 30594.8 31158.4 30052.4 30309.7 30530.4 31334.0 31402.4 34024.5 35338.8 35446.5 35499.1 35530.6 29236.1 29400.3 29939.0 30111.0 30374.8 23656.3 24367.5 24459.3 24589.0 24672.9 10:06:32 INFO - PID 5044 | 10:06:32 INFO - PID 5044 | [#2] jquery.html Cycles:180 Average:31473.61 Median:12485.67 stddev:85072.16 (681.4%) stddev-sans-first:85278.90 10:06:32 INFO - PID 5044 | Values: 438.9 449.1 451.9 463.1 467.4 18013.5 19500.6 20271.4 20353.7 20372.3 20702.4 22060.9 22542.8 22713.1 22931.3 5310.4 5486.3 5535.3 5551.3 5576.9 5447.3 5492.2 5683.4 5714.0 5722.2 5675.5 5692.1 5694.8 5700.9 5709.0 5499.1 5588.9 5612.6 5623.2 5624.4 4999.2 5098.0 5107.0 5117.0 5125.1 25089.9 25744.9 26207.0 26407.2 26445.0 2154.1 2176.0 2197.5 2218.7 2221.5 16703.2 16837.2 17421.5 17440.7 17572.9 2079.6 2147.7 2177.9 2182.8 2189.5 83911.0 103372.5 103413.5 103553.0 105044.0 12294.8 12551.5 12644.2 12754.8 12783.0 17559.3 17631.9 17649.7 17746.9 17849.0 8679.0 8819.2 8928.7 8955.0 9032.8 7961.2 8015.5 8032.2 8205.6 8212.7 7973.1 8103.4 8117.4 8265.6 8289.9 2258.3 2275.5 2282.0 2313.1 2406.6 998.3 1037.1 1038.5 1046.1 1054.4 19176.7 23785.4 25909.4 25913.4 26185.9 1059.7 1067.2 1069.9 1076.9 1109.9 11252.2 11760.0 11825.3 11912.8 11919.8 503597.0 508686.0 516794.0 521953.0 522632.0 89720.2 95546.0 97226.0 97989.5 101889.5 1222.5 1255.4 1271.3 1280.5 1283.3 17495.9 17775.6 18092.7 18108.5 18515.8 46218.8 46269.8 46404.0 47109.0 47484.8 56140.6 58415.0 58844.4 58881.6 59568.0 9190.4 9280.8 9429.8 9588.6 10026.9 13662.7 14140.4 14746.6 14782.6 15130.2 12411.6 12583.8 12770.9 12865.7 12970.6 11714.9 11730.6 11886.9 12285.0 12374.2 12356.9 12986.5 13123.0 13250.1 13606.9 9571.4 9572.4 9573.7 9859.9 10011.7 12938.2 12965.9 12985.0 13181.4 13204.3 10:06:32 INFO - PID 5044 | 10:06:32 INFO - PID 5044 | [#3] mootools.html Cycles:180 Average:7220.47 Median:3749.97 stddev:4724.25 (126.0%) stddev-sans-first:4717.09 10:06:32 INFO - PID 5044 | Values: 1375.2 1405.1 1423.7 1428.6 1431.9 11430.6 11789.5 11802.4 11845.1 11850.6 11337.8 11390.7 11664.0 11788.6 11806.4 2321.4 2371.5 2379.5 2382.8 2391.0 1982.3 2287.2 2361.0 2368.6 2370.1 2052.6 2300.0 2369.3 2370.3 2374.3 2215.9 2249.1 2253.4 2255.7 2255.9 2252.8 2255.6 2266.8 2272.5 2301.7 10783.6 10984.6 10987.0 10989.2 10989.8 6511.3 6755.8 6759.4 6766.4 6772.8 3751.4 4075.1 4100.5 4156.9 4166.3 1313.2 1338.1 1374.0 1429.6 1458.5 17516.4 17552.3 17579.0 17654.3 17658.2 5176.4 5206.9 5390.4 5418.9 5445.2 12497.7 12499.3 12514.4 12514.4 12524.2 6491.4 6498.1 6501.1 6501.2 6501.5 5850.6 6070.8 6099.4 6187.8 6212.3 4200.2 4260.7 4262.4 4299.4 4331.6 1112.7 1127.4 1190.7 1210.5 1225.4 709.6 836.9 854.6 864.6 875.2 5992.7 6011.0 6011.7 6042.0 6257.5 930.7 930.8 956.5 960.7 976.4 3748.6 3826.6 3903.1 3916.1 3919.4 15073.4 15339.8 15420.2 15472.3 15492.3 15193.2 15214.6 15218.3 15242.2 15261.4 9305.6 9536.2 9657.6 9717.8 9729.4 6708.8 6916.3 6988.7 7030.3 7074.3 13747.7 13773.8 13774.2 14041.4 14077.7 13810.5 13908.3 14040.6 14068.6 14149.0 9573.0 9628.4 9759.7 9806.2 9835.1 11360.3 11378.9 11455.7 11497.4 11551.6 9328.8 9475.4 9608.1 9728.5 9818.3 9420.6 9426.1 9443.9 9576.3 9611.2 8358.2 8495.8 8503.1 8512.0 8514.3 5652.8 5663.3 5715.4 5759.8 5884.1 5949.6 5972.0 5976.2 5991.6 6165.5 10:06:32 INFO - PID 5044 | 10:06:32 INFO - PID 5044 | [#4] prototype.html Cycles:180 Average:5194.53 Median:5860.15 stddev:1489.45 (25.4%) stddev-sans-first:1449.51 10:06:32 INFO - PID 5044 | Values: 400.3 428.5 430.1 432.0 436.3 5835.4 5856.2 5864.1 5872.4 5918.6 5669.4 5758.6 5791.7 5795.4 5809.4 4504.4 4543.7 4546.0 4546.5 4546.7 4570.6 4575.6 4578.1 4594.0 4604.4 4417.4 4495.4 4547.4 4555.4 4568.5 4518.3 4518.6 4532.5 4534.7 4566.7 4066.7 4085.0 4095.3 4102.8 4105.9 5527.2 5630.5 5736.6 5738.7 5745.1 5296.2 5365.7 5377.7 5379.0 5398.9 6130.8 6154.8 6164.7 6169.6 6182.8 4199.6 4314.4 4329.5 4337.2 4628.8 6098.5 6245.3 6282.3 6424.4 6487.5 4400.5 4404.6 4413.7 4414.4 4419.8 4272.7 4358.3 4471.8 4474.6 4493.5 5351.1 5431.8 5475.8 5485.3 5489.9 6120.9 6155.4 6175.8 6180.4 6220.8 1682.7 1715.7 1734.2 1735.1 1736.4 1236.8 1262.3 1267.3 1280.2 1284.1 5822.0 5854.6 5876.4 5894.8 5904.3 6282.7 6331.0 6331.8 6338.9 6340.4 5664.6 5833.2 5878.4 5889.3 5889.5 3307.2 3336.5 3347.0 3356.2 3359.2 6439.6 6538.4 6538.4 6554.7 6572.3 6419.3 6425.3 6434.2 6434.8 6444.6 6371.2 6505.2 6515.5 6536.4 6546.9 6234.4 6255.5 6266.5 6268.8 6303.9 5892.1 5953.9 6043.0 6167.9 6196.9 6469.3 6477.9 6496.4 6536.4 6563.0 4294.9 4892.9 4921.5 4930.2 4937.9 6295.2 6332.8 6341.3 6352.2 6356.6 6166.2 6167.3 6188.6 6192.9 6200.4 6054.6 6136.3 6169.9 6171.9 6178.8 6066.0 6152.9 6169.8 6175.3 6176.9 6034.9 6106.8 6125.0 6134.3 6162.7 5994.3 6004.7 6010.0 6023.8 6043.7 10:06:32 INFO - PID 5044 | 10:06:32 INFO - PID 5044 | [#5] yui.html Cycles:180 Average:18542.45 Median:3430.76 stddev:22507.92 (656.1%) stddev-sans-first:22531.73 10:06:32 INFO - PID 5044 | Values: 823.3 847.9 864.5 864.9 874.1 14266.4 14969.6 15102.8 15215.0 15255.8 15934.8 16074.0 16220.5 16509.4 18153.6 962.4 979.5 990.1 990.4 990.8 697.1 994.7 998.4 998.6 999.0 766.0 974.9 1000.6 1009.9 1012.6 986.0 991.6 992.7 997.0 1000.4 737.1 737.5 738.1 741.7 742.2 16318.8 16849.4 17066.8 17608.9 17765.1 18176.4 18485.2 18813.0 18867.9 18955.5 15099.5 15307.3 15324.4 15505.5 15517.4 3413.1 3423.8 3430.0 3430.1 3431.5 89917.0 89991.6 90809.0 91159.0 91925.0 12316.0 12321.1 12463.9 12468.3 12480.1 21386.3 21714.6 21722.7 21803.5 21923.3 7993.0 8132.8 8163.7 8174.2 8177.1 4314.5 4361.7 4393.3 4398.6 4401.2 5028.3 5128.8 5193.1 5199.1 5200.6 935.2 935.6 938.2 942.8 946.5 684.5 693.1 707.7 708.9 737.0 11280.3 12539.7 12693.5 12842.6 13085.7 698.4 708.8 735.7 743.6 746.6 5297.4 5402.6 5571.3 5604.6 5827.1 75756.2 76718.0 77267.0 78360.0 78739.0 64795.7 65212.7 66147.7 66237.0 66333.3 57500.4 57833.1 58232.7 58654.7 61692.4 11468.8 11568.7 11702.4 11818.8 11949.1 27297.7 29384.9 33636.7 34653.8 35756.1 55110.7 55577.7 56343.4 57201.7 59556.0 15817.1 15903.8 15943.4 16127.3 16234.4 18681.7 18792.2 18853.0 18920.3 19025.8 16217.4 16294.5 16319.8 16550.3 16631.4 17251.5 17503.6 17590.4 17618.8 18040.8 16636.8 16689.6 16720.7 16785.0 16987.2 11171.8 11252.3 11463.5 11495.3 11880.6 12804.8 13182.4 13313.5 13908.8 14061.4 10:06:32 INFO - PID 5044 | -------- Summary: end -------- 10:06:32 INFO - PID 5044 | 10:06:33 INFO - TEST-INFO | 5044: exit 0 10:06:33 INFO - TEST-OK | dromaeo_css | took 1114446ms 10:06:33 INFO - TEST-START | kraken 10:06:33 INFO - operating with platform_type : linux_ 10:06:33 INFO - Initialising browser for kraken test... 10:06:33 INFO - TEST-INFO | started process 5372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWB9ShJ/profile http://localhost:36343/getInfo.html) 10:06:36 INFO - PID 5372 | __metrics Screen width/height:1600/1200 10:06:36 INFO - PID 5372 | colorDepth:24 10:06:36 INFO - PID 5372 | Browser inner width/height: 1024/695 10:06:36 INFO - PID 5372 | __metrics 10:06:37 INFO - PID 5372 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:06:37 INFO - PID 5372 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:06:38 INFO - TEST-INFO | 5372: exit 0 10:06:38 INFO - Browser initialized. 10:06:38 INFO - Running cycle 1/1 for kraken test... 10:06:38 INFO - TEST-INFO | started process 5471 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWB9ShJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:50 INFO - PID 5471 | RSS: Main: 148643840 10:06:50 INFO - PID 5471 | 10:06:52 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36343/tests/kraken/driver.html?testName=audio-beat-detection) 10:06:53 INFO - PID 5471 | RSS: Main: 218230784 10:06:53 INFO - PID 5471 | 10:06:56 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36343/tests/kraken/driver.html?testName=audio-dft) 10:06:56 INFO - PID 5471 | RSS: Main: 178745344 10:06:56 INFO - PID 5471 | 10:06:59 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36343/tests/kraken/driver.html?testName=audio-fft) 10:07:00 INFO - PID 5471 | RSS: Main: 260980736 10:07:00 INFO - PID 5471 | 10:07:02 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36343/tests/kraken/driver.html?testName=audio-oscillator) 10:07:02 INFO - PID 5471 | RSS: Main: 173424640 10:07:02 INFO - PID 5471 | 10:07:04 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36343/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:07:05 INFO - PID 5471 | RSS: Main: 175431680 10:07:05 INFO - PID 5471 | 10:07:08 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36343/tests/kraken/driver.html?testName=imaging-darkroom) 10:07:09 INFO - PID 5471 | RSS: Main: 236318720 10:07:09 INFO - PID 5471 | 10:07:12 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36343/tests/kraken/driver.html?testName=imaging-desaturate) 10:07:13 INFO - PID 5471 | RSS: Main: 258486272 10:07:13 INFO - PID 5471 | 10:07:15 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36343/tests/kraken/driver.html?testName=json-parse-financial) 10:07:16 INFO - PID 5471 | RSS: Main: 276209664 10:07:16 INFO - PID 5471 | 10:07:17 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36343/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:07:18 INFO - PID 5471 | RSS: Main: 199172096 10:07:18 INFO - PID 5471 | 10:07:20 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36343/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:07:20 INFO - PID 5471 | RSS: Main: 204455936 10:07:20 INFO - PID 5471 | 10:07:22 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36343/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:07:23 INFO - PID 5471 | RSS: Main: 213422080 10:07:23 INFO - PID 5471 | 10:07:25 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36343/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:07:26 INFO - PID 5471 | RSS: Main: 222830592 10:07:26 INFO - PID 5471 | 10:07:29 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36343/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:07:30 INFO - PID 5471 | RSS: Main: 217763840 10:07:30 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | Cycle 1(1): loaded http://localhost:36343/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36343/tests/kraken/driver.html?testName=ai-astar) 10:07:31 INFO - PID 5471 | RSS: Main: 268083200 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | __start_tp_report 10:07:31 INFO - PID 5471 | _x_x_mozilla_page_load 10:07:31 INFO - PID 5471 | _x_x_mozilla_page_load_details 10:07:31 INFO - PID 5471 | |i|pagename|runs| 10:07:31 INFO - PID 5471 | |0;ai-astar;109;112;110;111;110;109;110;110;110;112 10:07:31 INFO - PID 5471 | |1;audio-beat-detection;145;123;117;168;120;119;118;120;142;128 10:07:31 INFO - PID 5471 | |2;audio-dft;161;161;160;161;160;160;160;161;160;162 10:07:31 INFO - PID 5471 | |3;audio-fft;74;74;74;102;75;77;74;79;97;74 10:07:31 INFO - PID 5471 | |4;audio-oscillator;82;81;110;83;83;82;96;82;83;117 10:07:31 INFO - PID 5471 | |5;imaging-gaussian-blur;113;113;113;112;113;113;113;113;113;113 10:07:31 INFO - PID 5471 | |6;imaging-darkroom;147;148;147;147;146;147;146;146;147;147 10:07:31 INFO - PID 5471 | |7;imaging-desaturate;94;94;94;94;94;94;93;94;95;95 10:07:31 INFO - PID 5471 | |8;json-parse-financial;46;45;45;46;46;47;49;46;46;46 10:07:31 INFO - PID 5471 | |9;json-stringify-tinderbox;54;53;53;54;54;54;54;53;55;54 10:07:31 INFO - PID 5471 | |10;stanford-crypto-aes;78;78;87;79;80;79;79;81;78;94 10:07:31 INFO - PID 5471 | |11;stanford-crypto-ccm;132;126;124;123;123;122;121;121;169;121 10:07:31 INFO - PID 5471 | |12;stanford-crypto-pbkdf2;188;181;180;182;180;187;183;178;179;182 10:07:31 INFO - PID 5471 | |13;stanford-crypto-sha256-iterative;66;66;67;67;65;75;65;64;67;66 10:07:31 INFO - PID 5471 | __end_tp_report 10:07:31 INFO - PID 5471 | __start_cc_report 10:07:31 INFO - PID 5471 | _x_x_mozilla_cycle_collect,1695 10:07:31 INFO - PID 5471 | __end_cc_report 10:07:31 INFO - PID 5471 | __startTimestamp1494608851933__endTimestamp 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | ------- Summary: start ------- 10:07:31 INFO - PID 5471 | Number of tests: 14 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.00 stddev:1.06 (1.0%) stddev-sans-first:1.01 10:07:31 INFO - PID 5471 | Values: 109.0 112.0 110.0 111.0 110.0 109.0 110.0 110.0 110.0 112.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:125.50 stddev:16.67 (13.3%) stddev-sans-first:16.77 10:07:31 INFO - PID 5471 | Values: 145.0 123.0 117.0 168.0 120.0 119.0 118.0 120.0 142.0 128.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.73 10:07:31 INFO - PID 5471 | Values: 161.0 161.0 160.0 161.0 160.0 160.0 160.0 161.0 160.0 162.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#3] audio-fft Cycles:10 Average:80.00 Median:74.50 stddev:10.48 (14.1%) stddev-sans-first:10.89 10:07:31 INFO - PID 5471 | Values: 74.0 74.0 74.0 102.0 75.0 77.0 74.0 79.0 97.0 74.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:82.50 stddev:13.27 (16.1%) stddev-sans-first:13.76 10:07:31 INFO - PID 5471 | Values: 82.0 81.0 110.0 83.0 83.0 82.0 96.0 82.0 83.0 117.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:07:31 INFO - PID 5471 | Values: 113.0 113.0 113.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 10:07:31 INFO - PID 5471 | Values: 147.0 148.0 147.0 147.0 146.0 147.0 146.0 146.0 147.0 147.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 10:07:31 INFO - PID 5471 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 95.0 95.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#8] json-parse-financial Cycles:10 Average:46.20 Median:46.00 stddev:1.14 (2.5%) stddev-sans-first:1.20 10:07:31 INFO - PID 5471 | Values: 46.0 45.0 45.0 46.0 46.0 47.0 49.0 46.0 46.0 46.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#9] json-stringify-tinderbox Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 10:07:31 INFO - PID 5471 | Values: 54.0 53.0 53.0 54.0 54.0 54.0 54.0 53.0 55.0 54.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:79.50 stddev:5.21 (6.6%) stddev-sans-first:5.39 10:07:31 INFO - PID 5471 | Values: 78.0 78.0 87.0 79.0 80.0 79.0 79.0 81.0 78.0 94.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#11] stanford-crypto-ccm Cycles:10 Average:128.20 Median:123.50 stddev:14.72 (11.9%) stddev-sans-first:15.55 10:07:31 INFO - PID 5471 | Values: 132.0 126.0 124.0 123.0 123.0 122.0 121.0 121.0 169.0 121.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:182.00 stddev:3.27 (1.8%) stddev-sans-first:2.65 10:07:31 INFO - PID 5471 | Values: 188.0 181.0 180.0 182.0 180.0 187.0 183.0 178.0 179.0 182.0 10:07:31 INFO - PID 5471 | 10:07:31 INFO - PID 5471 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.50 stddev:3.05 (4.6%) stddev-sans-first:3.22 10:07:31 INFO - PID 5471 | Values: 66.0 66.0 67.0 67.0 65.0 75.0 65.0 64.0 67.0 66.0 10:07:31 INFO - PID 5471 | -------- Summary: end -------- 10:07:31 INFO - PID 5471 | 10:07:32 INFO - TEST-INFO | 5471: exit 0 10:07:33 INFO - TEST-OK | kraken | took 59529ms 10:07:33 INFO - SUITE-END | took 1173s 10:07:33 INFO - Completed test suite (00:19:34) 10:07:33 INFO - javascript benchmark 10:07:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7648.279930958742, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3298.63714991351, 3324.4680851063836, 3336.3160552086815, 3343.682350176732, 3345.8327083645827, 5738.7991420300295, 5763.394843541426, 5815.38938411467, 5826.737796799143, 5847.970760146193, 5812.302523697152, 5830.3586605473365, 5845.5031322337545, 5889.087191485327, 5965.0, 4789.0, 5054.772535235923, 5134.204198349253, 5187.144121219994, 5259.921101183466, 5230.6600070995455, 5294.41761406245, 5304.443033481487, 5310.362756469229, 5311.468853114697, 5136.5377116059735, 5176.042432150064, 5202.037623039712, 5222.32109825724, 5235.5549778268705, 5234.241035049935, 5244.842654720364, 5246.396664383607, 5300.12547929595, 5321.547668448186, 5188.7146206958605, 5212.4266330703595, 5224.08578498765, 5237.869053273661, 5279.9968006078725, 5738.311402631669, 5770.394108618619, 5846.356900740915, 5880.764769409218, 5886.852828679274, 6208.596441231305, 6304.810855674337, 6313.652749098797, 6320.367963203643, 6323.988161894074, 6409.519286053518, 6420.678966051679, 6423.807285781388, 6429.742810287583, 6430.871382572323, 5897.882042359129, 5958.6424814511265, 5963.761449542013, 5966.522678185735, 5981.880362392728, 6365.14070600463, 6403.0715546246465, 6424.453921416732, 6509.576877503042, 6555.639439830855, 6076.8784624308155, 6111.53932221086, 6122.77432918503, 6141.486007784275, 6268.341824108403, 6268.686565671698, 6348.936510634834, 6387.0, 6388.041793730885, 6392.520560957947, 6341.365863413714, 6344.016677415008, 6344.016677415008, 6345.17512723343, 6354.777582784581, 6145.108959200891, 6210.285817130997, 6237.064440333896, 6320.905186422207, 6355.428011478989, 4752.0, 4819.807207711722, 4837.443693975168, 4838.2016967200725, 4855.975720121377, 4259.893502662459, 4271.316589345752, 4274.7435153890865, 4276.786160691953, 4305.0, 5926.881462370815, 5927.644341339534, 5950.0, 5952.672603006789, 5955.374685657944, 6121.300552271563, 6276.748930042747, 6278.434940855345, 6360.332165122688, 6372.139761132188, 5938.970305148447, 5955.404459554096, 5958.076498142795, 5959.374265702126, 5961.254843144607, 5402.35171779389, 5402.51377376038, 5406.243125962368, 5406.297181366476, 5407.513323800877, 6349.460295874902, 6512.283648798533, 6525.967370163119, 6630.602163870183, 6644.103046088716, 6375.617462952052, 6403.487720982217, 6424.678766061772, 6426.325235850355, 6432.581882177644, 6638.966805165944, 6663.866722665616, 6670.232923213893, 6670.966645166937, 6673.399394054559, 6113.969430152999, 6115.453310713017, 6164.537659675452, 6225.906611400742, 6285.654289014148, 6343.841403964938, 6406.679666016774, 6425.261094973951, 6427.903581446376, 6431.035344698333, 6184.969075154595, 6220.937790622036, 6223.159873417212, 6227.782027629011, 6230.252369715663, 6049.576529642883, 6078.969605151946, 6105.15650299495, 6117.785877494266, 6197.225346831646, 6125.601746185282, 6149.569530132848, 6150.7847225346895, 6164.445199932027, 6173.84565385869, 6141.539384546088, 6171.691415429301, 6198.814035578759, 6207.16203312547, 6208.534359923115, 5761.827145185651, 5776.768929242951, 5843.707814609167, 5980.910286345621, 6033.909491357545], "value": 5783.964888598774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.9671064802084, 790.3479629305823, 809.3606051219537, 1137.5961533655557, 1156.1213477756903, 28013.57979630307, 36513.81743091287, 37127.81436092819, 37199.814000930026, 37226.44160337597, 37850.0537486563, 37972.81013594928, 38079.42880856789, 38177.42733858994, 38243.23513529728, 5568.916466253009, 5714.314282286111, 7195.784126476214, 7320.597367144805, 7325.926740732578, 7033.331833475837, 7199.0281312022735, 7200.279972002811, 7204.135503739559, 7204.603746793924, 7207.063081799385, 7284.890726639104, 7287.890681639779, 7288.8177795555, 7324.340809327159, 7226.747063852767, 7291.598962057111, 7300.707971681127, 7308.780736577911, 7314.670839812221, 7129.287071292881, 7199.0641216642025, 7204.063471748639, 7204.243554426815, 7206.891896621555, 34281.31437371264, 34388.82805585956, 34396.312073758636, 34445.31109377823, 34511.0, 3557.2174121693447, 3568.0901370150705, 3598.442241452579, 3600.261946301002, 3604.711623070148, 26964.191074267805, 26986.92052317905, 27033.729662703317, 27064.729352706418, 27066.0, 3680.8159592020293, 3687.078230442389, 3687.1888184599347, 3698.9445158322646, 3706.684931780802, 183789.0, 184174.0, 184641.0, 184719.0, 184910.0, 45617.0, 45642.54357456449, 45667.0, 45676.314855277196, 45695.31457028147, 47551.5244847554, 47741.5225847744, 47744.9416799569, 47760.761196193795, 47817.0, 14463.638409039648, 14607.050541714967, 14704.852951470562, 14737.852621473648, 14762.852371476363, 10710.625128120479, 10899.455027248607, 11215.66353009411, 11943.940280298717, 12011.63965081049, 12695.587838310876, 12863.807042894365, 12866.935665321613, 13013.479460821462, 13076.084674072723, 5204.609654275944, 5216.070054079939, 5245.527902488794, 5290.550303224192, 5372.946270537323, 1035.1822060572215, 1077.2082519348273, 1088.678839742278, 1095.3756358875364, 1113.565709373345, 24980.875095624404, 25013.499730005296, 25033.62449563258, 25090.623640645405, 25103.497930041296, 1163.6218229075414, 1170.6780635325354, 1181.3502573584494, 1193.7373777768878, 1193.7612477504533, 12502.687432814071, 13330.0, 13342.06605537603, 13345.799813002814, 13361.933190333986, 225512.0, 226279.0, 226930.0, 227050.0, 227199.0, 48860.267095993586, 48980.26529602059, 48995.26507102396, 49033.509664903606, 49161.50838491641, 1442.5672298310465, 1449.615851799274, 1500.1599104501513, 1505.4128889733013, 1510.1543135843742, 23518.529629406974, 24673.136440224505, 24876.751232487444, 24926.127585534417, 24971.0, 37178.0, 46569.0, 47529.76235118802, 47540.28689569728, 47548.28677569908, 191554.0, 192166.0, 192523.0, 192793.0, 193261.0, 28556.286092847844, 28684.13947581576, 30904.53643195309, 31117.8444107778, 31221.687783121877, 38072.238555228185, 38134.42798357971, 40141.79929100336, 40354.394684079176, 40533.18933621252, 29858.701412986462, 30464.238394040327, 30467.542986854773, 30594.847025764728, 31158.376832463677, 30052.398952021274, 30309.69690303157, 30530.389392212477, 31334.0, 31402.371952560363, 34024.48963265504, 35338.82330588331, 35446.46830297496, 35499.112522187155, 35530.644693552735, 29236.122916312546, 29400.26499337534, 29939.0, 30111.0, 30374.84812575923, 23656.29031129069, 24367.51264974726, 24459.285155801972, 24589.0, 24672.876635616707], "value": 16391.384578610952, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.88808353869797, 449.1264490565849, 451.9435070616173, 463.10620502430294, 467.3970577954439, 18013.549661258476, 19500.609987800235, 20271.39185824424, 20353.694694579575, 20372.286969956054, 20702.378928632166, 22060.917306529856, 22542.77457225427, 22713.091476340967, 22931.312060638167, 5310.362756469229, 5486.341639003325, 5535.280413546254, 5551.333839939213, 5576.888462230753, 5447.291852059227, 5492.203630473579, 5683.374828768832, 5713.971430142843, 5722.170285308628, 5675.5459563234845, 5692.146178073302, 5694.77220911163, 5700.914486282709, 5709.029464990961, 5499.092649712793, 5588.888222235553, 5612.607117501777, 5623.240862483553, 5624.381318055031, 4999.175136102539, 5098.0, 5106.974465127651, 5116.974415127937, 5125.077486052509, 25089.87455062713, 25744.8712756435, 26207.0, 26407.207783766327, 26445.0, 2154.138344662132, 2175.98912005439, 2197.5275315807016, 2218.7448443429053, 2221.466847956479, 16703.248353824107, 16837.24232409532, 17421.47735567935, 17440.738388924176, 17572.91213543924, 2079.5840831833543, 2147.742270927483, 2177.923772667965, 2182.847200695952, 2189.4526368407896, 83911.0, 103372.48313758458, 103413.48293258561, 103553.0, 105044.0, 12294.815577766341, 12551.497940082294, 12644.178128421807, 12754.80867786984, 12783.0, 17559.297628094988, 17631.911840440716, 17649.7352539712, 17746.91126544359, 17849.0, 8678.95660521706, 8819.162179592928, 8928.68749593774, 8955.0, 9032.774180645536, 7961.203879612109, 8015.519068855887, 8032.208459199453, 8205.58972051395, 8212.65939207323, 7973.122956474783, 8103.351731861437, 8117.391195660349, 8265.586720663943, 8289.917100828914, 2258.254775923941, 2275.485588424767, 2281.9657705134437, 2313.0747700919765, 2406.5908795504806, 998.32612986234, 1037.1029059863165, 1038.5378506564657, 1046.1369370269558, 1054.3989925742408, 19176.71234931477, 23785.405364866016, 25909.35226619312, 25913.352166195997, 26185.869070654524, 1059.6873922192972, 1067.1782727299935, 1069.9358038517714, 1076.9299995500267, 1109.8668159820872, 11252.15608829341, 11760.0, 11825.349605771593, 11912.821307680391, 11919.761604767857, 503597.0, 508686.0, 516794.0, 521953.0, 522632.0, 89720.23324722187, 95546.0, 97226.0, 97989.51005244929, 101889.49055254825, 1222.4999875255126, 1255.428779905141, 1271.2753730373784, 1280.4558062823448, 1283.2813624370383, 17495.912520437316, 17775.555611109827, 18092.72860907061, 18108.547286317946, 18515.81484185195, 46218.7689061566, 46269.76865115787, 46404.0, 47109.0, 47484.7625761869, 56140.56009381384, 58415.0, 58844.4115558839, 58881.578947368216, 59568.0, 9190.35667503295, 9280.814383712423, 9429.764255893657, 9588.568514417007, 10026.899731002888, 13662.726745465234, 14140.363683634056, 14746.63133421673, 14782.6304342388, 15130.243487825785, 12411.62765117048, 12583.811242831182, 12770.936145319214, 12865.742685146057, 12970.610881673565, 11714.941425292818, 11730.648080557597, 11886.881131188577, 12285.0, 12374.195677281308, 12356.876431235572, 12986.48054077864, 13123.0, 13250.138740981805, 13606.93196534011, 9571.425714457155, 9572.377795443273, 9573.712788616353, 9859.852102218329, 10011.749706257402, 12938.223706577259, 12965.870341296466, 12985.0, 13181.354814207545, 13204.339783011003], "value": 10214.224623368873, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.1611516974647, 1405.0796728143064, 1423.736608727385, 1428.5928510374545, 1431.856814318568, 11430.599929002476, 11789.469473873674, 11802.350870702108, 11845.111616628767, 11850.585229516968, 11337.773244535125, 11390.658280251606, 11664.0, 11788.646340609774, 11806.409679516033, 2321.3964369263986, 2371.5019845832394, 2379.5478859016816, 2382.761723827612, 2391.0435825669697, 1982.2863769043133, 2287.2223444029028, 2360.9645855312183, 2368.6328619064075, 2370.087516306226, 2052.5894821035777, 2299.9655005174936, 2369.277370402027, 2370.2533701883935, 2374.25211058516, 2215.944601384962, 2249.100359856054, 2253.357793028987, 2255.7293124825046, 2255.909763609454, 2252.7972482476493, 2255.6052690779047, 2266.7506574276817, 2272.4546108933737, 2301.6662583925404, 10783.5686572537, 10984.615538456115, 10987.0, 10989.230753847234, 10989.835152472719, 6511.316311787289, 6755.7973260802255, 6759.391654751096, 6766.357196066366, 6772.762953296611, 3751.381022131345, 4075.1442197138635, 4100.5489396166395, 4156.875293741193, 4166.250074986501, 1313.2186349122353, 1338.1235290884485, 1373.9519116830863, 1429.5854202281325, 1458.4749490183526, 17516.38692645751, 17552.297908083663, 17579.0, 17654.293828246853, 17658.205380757896, 5176.352955880515, 5206.921896171561, 5390.407055223922, 5418.91871621926, 5445.156000819879, 12497.687557810945, 12499.250044997329, 12514.3742812859, 12514.3742812859, 12524.248545087325, 6491.448226900767, 6498.057781621638, 6501.0898474213645, 6501.2198536175965, 6501.544891857619, 5850.590458667937, 6070.7875224366935, 6099.3900609938655, 6187.752489900444, 6212.254529456494, 4200.15996800635, 4260.68044896634, 4262.360645903139, 4299.441072660534, 4331.610155086058, 1112.7051331397186, 1127.4081107418672, 1190.678516800459, 1210.4552951171831, 1225.3811825028304, 709.5742554467381, 836.8617683686056, 854.6239654551978, 864.6325311742485, 875.2079368171815, 5992.700364981821, 6010.9699451502465, 6011.729472173763, 6041.969790151021, 6257.4994000480765, 930.7300882743929, 930.8417569013284, 956.4787190980932, 960.70698436976, 976.4092723902079, 3748.5689145748042, 3826.559945606291, 3903.10228647407, 3916.060145565044, 3919.4300813532573, 15073.397064117313, 15339.846601533842, 15420.151891646066, 15472.303746331441, 15492.30284637194, 15193.164375959428, 15214.619634509227, 15218.315175817115, 15242.161681107646, 15261.389544418098, 9305.627774889064, 9536.189423898908, 9657.613695452103, 9717.80564388694, 9729.416235025921, 6708.765193218214, 6916.273791251846, 6988.685509151998, 7030.3321184487395, 7074.292570742885, 13747.725045499234, 13773.793393098911, 13774.173549587056, 14041.368138434, 14077.718445630826, 13810.516631917835, 13908.304584770518, 14040.613095773539, 14068.648283792987, 14148.957974035631, 9573.0, 9628.374155679858, 9759.658411955548, 9806.166475849348, 9835.114839664464, 11360.318380897173, 11378.943105284421, 11455.713607159887, 11497.367644779617, 11551.595694150665, 9328.813423731623, 9475.431474111576, 9608.13526782593, 9728.513574321114, 9818.263630227619, 9420.62317507292, 9426.104520070583, 9443.858342124737, 9576.329656923948, 9611.231101511723, 8358.247757701836, 8495.830083398174, 8503.107173746668, 8511.95744021276, 8514.276286515715, 5652.802151924828, 5663.292088488939, 5715.371309155907, 5759.827205183851, 5884.08796636522, 5949.6430214187285, 5971.970140149445, 5976.163337132718, 5991.610545314715, 6165.5375846810775], "value": 5371.593180370115, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.27749911409927, 428.4943766355701, 430.1032347555347, 431.9847909502171, 436.3236982676851, 5835.416458354162, 5856.180134781134, 5864.120381942711, 5872.442117998794, 5918.556108291879, 5669.433056694328, 5758.568107391952, 5791.71041447928, 5795.420457954203, 5809.448102430262, 4504.436945381827, 4543.70465919715, 4546.045330480603, 4546.454425468948, 4546.727196368212, 4570.588647021767, 4575.565321294472, 4578.1301552705045, 4594.0, 4604.401427814396, 4417.3815665806815, 4495.415595972519, 4547.363369128325, 4555.362249285086, 4568.47462541807, 4518.277075667877, 4518.593326600589, 4532.501424843264, 4534.684941367, 4566.675664057419, 4066.7356621819486, 4085.0400155963325, 4095.344744840841, 4102.774347410891, 4105.8973525661795, 5527.198556209327, 5630.493255607015, 5736.56975726818, 5738.713064346808, 5745.138229265602, 5296.179092240709, 5365.731713414314, 5377.6504527206, 5379.0, 5398.8920221595745, 6130.846728831771, 6154.753809847601, 6164.660943648098, 6169.598976066542, 6182.752689892399, 4199.601037901394, 4314.3528470729325, 4329.480462344507, 4337.154254920292, 4628.819650988976, 6098.481629061535, 6245.3442388549, 6282.277538083134, 6424.357564243538, 6487.481001519867, 4400.515943246241, 4404.6256068233915, 4413.735175889393, 4414.359917811899, 4419.845305414295, 4272.6795490338345, 4358.346248062816, 4471.821127154943, 4474.619657329098, 4493.5281795411665, 5351.143816989263, 5431.809886654027, 5475.808346707846, 5485.259489968803, 5489.862753431116, 6120.877582448326, 6155.415235552616, 6175.814725558241, 6180.381961803783, 6220.813375598739, 1682.6887025900248, 1715.7426386041943, 1734.17626627351, 1735.1150913034191, 1736.3575477073564, 1236.814477828315, 1262.3498898067503, 1267.258653687603, 1280.1678908709414, 1284.0882973089024, 5821.970890145522, 5854.648721076664, 5876.44173803488, 5894.823155305347, 5904.258243818084, 6282.685865706696, 6331.0, 6331.841703957346, 6338.904916426257, 6340.397662222082, 5664.5751568631695, 5833.154192642043, 5878.441548052928, 5889.293284805765, 5889.528837692974, 3307.1732066983254, 3336.51620515024, 3346.983265083659, 3356.1945133167865, 3359.193793489545, 6439.581427207217, 6538.411542961157, 6538.444232240217, 6554.70503827329, 6572.309907459744, 6419.325970773095, 6425.293217746044, 6434.163558737428, 6434.806955791334, 6444.613323200623, 6371.203599550056, 6505.243584232231, 6515.478761699147, 6536.444402225769, 6546.934530654633, 6234.407731265523, 6255.46828519581, 6266.467350275278, 6268.811935641938, 6303.936960630335, 5892.114523352486, 5953.851153721192, 6042.969785151046, 6167.876642467215, 6196.938030619636, 6469.320721324193, 6477.935220647733, 6496.382843629847, 6536.411722944958, 6562.967185164234, 4294.914101718011, 4892.899097702988, 4921.4832442593015, 4930.161872481615, 4937.851864444073, 6295.213098362705, 6332.796768613948, 6341.302456729663, 6352.237731472253, 6356.555041147075, 6166.167567378347, 6167.290761562478, 6188.597741146811, 6192.938070619236, 6200.410960958701, 6054.606450580698, 6136.325004249618, 6169.907451388322, 6171.907421388593, 6178.845528861634, 6066.028540724194, 6152.907706384318, 6169.784057557964, 6175.2589689237575, 6176.9073463897175, 6034.8793024138395, 6106.816795496142, 6125.0, 6134.325224225242, 6162.661053642093, 5994.250718660168, 6004.708987567519, 6009.956614751006, 6023.819285421444, 6043.667598282137], "value": 4753.019355236054, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.3331001888467, 847.940644154909, 864.5072308783991, 864.9481031138129, 874.0865795243969, 14266.358013889374, 14969.5509134726, 15102.773458398106, 15215.0, 15255.771163432557, 15934.760978585331, 16073.99999999997, 16220.513384598451, 16509.422170224043, 18153.636927261447, 962.4225464721163, 979.5102448775613, 990.10395591989, 990.420603946692, 990.7721224118457, 697.0728930522394, 994.6518718448558, 998.3760149906309, 998.6454808542971, 998.9700308990706, 765.9846803063936, 974.9415035097882, 1000.6397696829135, 1009.9394036357805, 1012.5696578953952, 985.9950700246488, 991.5686676295799, 992.6823416506721, 996.9551370188286, 1000.4247557654378, 737.0712901743788, 737.4800765460337, 738.1032046064058, 741.7107328142029, 742.2243755275696, 16318.83681163185, 16849.41027064059, 17066.82933170665, 17608.91195544014, 17765.111744412858, 18176.363827266116, 18485.168167432497, 18813.0, 18867.905660471748, 18955.526111847175, 15099.471518496799, 15307.311170997333, 15324.394852875039, 15505.534833955, 15517.379304827906, 3413.0784688134313, 3423.8116903570294, 3429.9657003430148, 3430.0567343980356, 3431.4681224410256, 89917.0, 89991.55004225002, 90809.0, 91159.0, 91925.0, 12316.0, 12321.07591930591, 12463.875361246453, 12468.314242716557, 12480.063995200215, 21386.251481198393, 21714.565708686056, 21722.674159887614, 21803.454913627287, 21923.342299731034, 7993.000874890638, 8132.75601731949, 8163.7142700005215, 8174.182581741778, 8177.141400152898, 4314.460692413449, 4361.694681372337, 4393.29707246839, 4398.64810815134, 4401.185780630593, 5028.321176641107, 5128.820491282787, 5193.11717008102, 5199.11615025439, 5200.583953283804, 935.1910597333393, 935.6023689931752, 938.216589148069, 942.8067246214509, 946.4652471353663, 684.4968947823346, 693.0816667915033, 707.7310621963621, 708.9078419805393, 736.9852602947911, 11280.2667826591, 12539.74920501603, 12693.49226030967, 12842.550510732079, 13085.672858178621, 698.4482259015423, 708.8227943014247, 735.6910097758954, 743.605888878895, 746.637880627895, 5297.390800058042, 5402.594805389612, 5571.331440227199, 5604.551635869121, 5827.125931110282, 75756.24243757493, 76718.0, 77267.0, 78360.0, 78739.0, 64795.67602162054, 65212.67393663001, 66147.66926165434, 66237.0, 66333.33666663368, 57500.42499575034, 57833.132503012486, 58232.7088364564, 58654.706726466095, 61692.383076169564, 11468.770624587629, 11568.710782230512, 11702.35637039971, 11818.822717659414, 11949.10381721357, 27297.676952993974, 29384.853075734485, 33636.66363336433, 34653.82673086618, 35756.10609734777, 55110.72444637751, 55577.72211138919, 56343.43656563382, 57201.71399142977, 59556.0, 15817.130057846929, 15903.761443578587, 15943.36226550925, 16127.274272657523, 16234.350625975301, 18681.71977420367, 18792.248310067444, 18853.0, 18920.33778817735, 19025.809741902405, 16217.35130594763, 16294.511164665078, 16319.83680163183, 16550.255238514535, 16631.417900373428, 17251.482455526355, 17503.562410939827, 17590.38433654816, 17618.82381176172, 18040.819591803913, 16636.83363166353, 16689.582760431087, 16720.74918876242, 16785.0, 16987.150642468074, 11171.832422513831, 11252.26860254081, 11463.484143213409, 11495.252808567751, 11880.584179553674, 12804.807927881275, 13182.406791694206, 13313.46746130144, 13908.791368129689, 14061.437542498186], "value": 8095.319582948635, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 110.0, 111.0, 110.0, 109.0, 110.0, 110.0, 110.0, 112.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 123.0, 117.0, 168.0, 120.0, 119.0, 118.0, 120.0, 142.0, 128.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 160.0, 160.0, 160.0, 161.0, 160.0, 162.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 102.0, 75.0, 77.0, 74.0, 79.0, 97.0, 74.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 110.0, 83.0, 83.0, 82.0, 96.0, 82.0, 83.0, 117.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 45.0, 45.0, 46.0, 46.0, 47.0, 49.0, 46.0, 46.0, 46.0], "value": 46.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 54.0, 54.0, 54.0, 53.0, 55.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 87.0, 79.0, 80.0, 79.0, 79.0, 81.0, 78.0, 94.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 126.0, 124.0, 123.0, 123.0, 122.0, 121.0, 121.0, 169.0, 121.0], "value": 128.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 181.0, 180.0, 182.0, 180.0, 187.0, 183.0, 178.0, 179.0, 182.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 65.0, 75.0, 65.0, 64.0, 67.0, 66.0], "value": 66.8, "unit": "score"}]}]} 10:07:33 INFO - Return code: 0 10:07:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:07:33 INFO - # TBPL SUCCESS # 10:07:33 INFO - Running post-action listener: _resource_record_post_action 10:07:33 INFO - [mozharness: 2017-05-12 17:07:33.254716Z] Finished run-tests step (success) 10:07:33 INFO - Running post-run listener: _resource_record_post_run 10:07:33 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 425984; Write bytes: 368689152; Read time: 96; Write time: 243276 10:07:33 INFO - TinderboxPrint: CPU usage
12.9% 10:07:33 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 96 10:07:33 INFO - TinderboxPrint: I/O write bytes / time
368,689,152 / 243,276 10:07:33 INFO - TinderboxPrint: CPU idle
8,278.4 (87.1%) 10:07:33 INFO - TinderboxPrint: CPU user
1,186.9 (12.5%) 10:07:33 INFO - TinderboxPrint: Swap in / out
0 / 0 10:07:33 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112107520; Read time: 0; Write time: 109668 10:07:33 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 425984; Write bytes: 256581632; Read time: 96; Write time: 133608 10:07:33 INFO - Running post-run listener: _upload_blobber_files 10:07:33 INFO - Blob upload gear active. 10:07:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:07:33 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 10:07:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:07:34 INFO - (blobuploader) - INFO - Open directory for files ... 10:07:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:07:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:07:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:35 INFO - (blobuploader) - INFO - Done attempting. 10:07:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:07:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:07:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:38 INFO - (blobuploader) - INFO - Done attempting. 10:07:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:07:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:07:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:38 INFO - (blobuploader) - INFO - Done attempting. 10:07:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:07:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:07:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:39 INFO - (blobuploader) - INFO - Done attempting. 10:07:39 INFO - (blobuploader) - INFO - Iteration through files over. 10:07:39 INFO - Return code: 0 10:07:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:07:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:07:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03e32c23dcdd01bfb627fe2474c14b5310812350f82c25292502fb5d5790e28c028be8a93333e914e9a3aab705d0e70ade16dae7081b20c82f2912c370adce67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41969ff290b79f719e8d9b1450f1e8765f2a6009e2d6198a96d9978e6696d9cbc5f5955b89e185f492bc064882ee0025e3ee81c523ee2ffc7a27634e328d31c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8accdc2ccdbe75a60de4ebc58792757c79b74a2ff7e634c991a0e5a6a3c4bab3603399e2c73a3af27bfc7604c21c43a82d345af0fcd3612e98d09952c8de06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a92d1a879c24e3130f500d93d59d17a884040f62a7688703f5e1a260cf3a204ba313b38dffeb075a8603b558215646e9be559d77bb739e1a24cfe001834629f2"} 10:07:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:07:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:07:39 INFO - Contents: 10:07:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03e32c23dcdd01bfb627fe2474c14b5310812350f82c25292502fb5d5790e28c028be8a93333e914e9a3aab705d0e70ade16dae7081b20c82f2912c370adce67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41969ff290b79f719e8d9b1450f1e8765f2a6009e2d6198a96d9978e6696d9cbc5f5955b89e185f492bc064882ee0025e3ee81c523ee2ffc7a27634e328d31c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8accdc2ccdbe75a60de4ebc58792757c79b74a2ff7e634c991a0e5a6a3c4bab3603399e2c73a3af27bfc7604c21c43a82d345af0fcd3612e98d09952c8de06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a92d1a879c24e3130f500d93d59d17a884040f62a7688703f5e1a260cf3a204ba313b38dffeb075a8603b558215646e9be559d77bb739e1a24cfe001834629f2"} 10:07:39 INFO - Running post-run listener: copy_logs_to_upload_dir 10:07:39 INFO - Copying logs to upload dir... 10:07:39 INFO - mkdir: /builds/slave/test/build/upload/logs 10:07:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.548604 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-12 10:07:39.551478) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 10:07:39.554991) ========= 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=9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03e32c23dcdd01bfb627fe2474c14b5310812350f82c25292502fb5d5790e28c028be8a93333e914e9a3aab705d0e70ade16dae7081b20c82f2912c370adce67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41969ff290b79f719e8d9b1450f1e8765f2a6009e2d6198a96d9978e6696d9cbc5f5955b89e185f492bc064882ee0025e3ee81c523ee2ffc7a27634e328d31c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8accdc2ccdbe75a60de4ebc58792757c79b74a2ff7e634c991a0e5a6a3c4bab3603399e2c73a3af27bfc7604c21c43a82d345af0fcd3612e98d09952c8de06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a92d1a879c24e3130f500d93d59d17a884040f62a7688703f5e1a260cf3a204ba313b38dffeb075a8603b558215646e9be559d77bb739e1a24cfe001834629f2"} build_url:https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/LSGaJGHfTrmZDJ2dFRsIjA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03e32c23dcdd01bfb627fe2474c14b5310812350f82c25292502fb5d5790e28c028be8a93333e914e9a3aab705d0e70ade16dae7081b20c82f2912c370adce67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41969ff290b79f719e8d9b1450f1e8765f2a6009e2d6198a96d9978e6696d9cbc5f5955b89e185f492bc064882ee0025e3ee81c523ee2ffc7a27634e328d31c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8accdc2ccdbe75a60de4ebc58792757c79b74a2ff7e634c991a0e5a6a3c4bab3603399e2c73a3af27bfc7604c21c43a82d345af0fcd3612e98d09952c8de06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a92d1a879c24e3130f500d93d59d17a884040f62a7688703f5e1a260cf3a204ba313b38dffeb075a8603b558215646e9be559d77bb739e1a24cfe001834629f2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 10:07:39.580558) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:07:39.580931) ========= 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=9f7544b6a75ada7b6b576032000001a7-1494606426.48347-1470457608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:07:39.664280) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 10:07:39.664637) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 10:07:39.664957) ========= ========= Total master_lag: 0.16 =========