builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-057 starttime: 1495737560.66 results: success (0) revision: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:20.662412) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:20.662866) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:20.663141) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:20.678704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:20.679112) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-25 11:39:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.1M=0s 2017-05-25 11:39:21 (69.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082910 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:20.775135) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:20.775454) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014415 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:20.828604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-25 11:39:20.831961) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d3ce3e3812c9372a12b6416f0ceedd2e768b5277 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d3ce3e3812c9372a12b6416f0ceedd2e768b5277 --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=934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-25 11:39:21,289 truncating revision to first 12 chars 2017-05-25 11:39:21,289 Setting DEBUG logging. 2017-05-25 11:39:21,289 attempt 1/10 2017-05-25 11:39:21,289 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d3ce3e3812c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 11:39:23,070 unpacking tar archive at: mozilla-beta-d3ce3e3812c9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.107157 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-25 11:39:22.952789) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:22.953226) ========= script_repo_revision: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:22.953674) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:22.953988) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 11:39:22.966358) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-25 11:39:22.966713) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:39:23 INFO - MultiFileLogger online at 20170525 11:39:23 in /builds/slave/test 11:39:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 11:39:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:39:23 INFO - {'append_to_log': False, 11:39:23 INFO - 'base_work_dir': '/builds/slave/test', 11:39:23 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 11:39:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:39:23 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 11:39:23 INFO - 'buildbot_json_path': 'buildprops.json', 11:39:23 INFO - 'config_files': ('talos/linux_config.py',), 11:39:23 INFO - 'default_actions': ('clobber', 11:39:23 INFO - 'read-buildbot-config', 11:39:23 INFO - 'download-and-extract', 11:39:23 INFO - 'populate-webroot', 11:39:23 INFO - 'create-virtualenv', 11:39:23 INFO - 'install', 11:39:23 INFO - 'run-tests'), 11:39:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:39:23 INFO - 'download_minidump_stackwalk': True, 11:39:23 INFO - 'download_symbols': 'ondemand', 11:39:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:39:23 INFO - 'tooltool.py': '/tools/tooltool.py', 11:39:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:39:23 INFO - '/tools/misc-python/virtualenv.py')}, 11:39:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:39:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:39:23 INFO - 'gecko_profile': False, 11:39:23 INFO - 'gecko_profile_interval': 0, 11:39:23 INFO - 'installer_path': 'installer.exe', 11:39:23 INFO - 'log_level': 'info', 11:39:23 INFO - 'log_name': 'talos', 11:39:23 INFO - 'log_to_console': True, 11:39:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:39:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:39:23 INFO - 'opt_config_files': (), 11:39:23 INFO - 'pip_index': False, 11:39:23 INFO - 'suite': 'dromaeojs', 11:39:23 INFO - 'system_bits': '32', 11:39:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:39:23 INFO - 'title': 'talos-linux64-ix-057', 11:39:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:39:23 INFO - 'use_talos_json': True, 11:39:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:39:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:39:23 INFO - 'work_dir': 'build'} 11:39:23 INFO - [mozharness: 2017-05-25 18:39:23.525587Z] Running clobber step. 11:39:23 INFO - Running pre-action listener: _resource_record_pre_action 11:39:23 INFO - Running main action method: clobber 11:39:23 INFO - rmtree: /builds/slave/test/build 11:39:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:39:24 INFO - Running post-action listener: _resource_record_post_action 11:39:24 INFO - [mozharness: 2017-05-25 18:39:24.554843Z] Finished clobber step (success) 11:39:24 INFO - [mozharness: 2017-05-25 18:39:24.554942Z] Running read-buildbot-config step. 11:39:24 INFO - Running pre-action listener: _resource_record_pre_action 11:39:24 INFO - Running main action method: read_buildbot_config 11:39:24 INFO - Using buildbot properties: 11:39:24 INFO - { 11:39:24 INFO - "project": "", 11:39:24 INFO - "product": "firefox", 11:39:24 INFO - "who": "ryanvm@gmail.com", 11:39:24 INFO - "installer_path": "public/build/target.tar.bz2", 11:39:24 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 11:39:24 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 11:39:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 11:39:24 INFO - "stage_platform": "linux64", 11:39:24 INFO - "basedir": "/builds/slave/test", 11:39:24 INFO - "buildnumber": 58, 11:39:24 INFO - "platform": "ubuntu64_hw", 11:39:24 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:39:24 INFO - "slavebuilddir": "test", 11:39:24 INFO - "taskId": "I6u2zLLrSY6lVfARl8a8VA", 11:39:24 INFO - "branch": "mozilla-beta", 11:39:24 INFO - "script_repo_revision": "production", 11:39:24 INFO - "revision": "d3ce3e3812c9372a12b6416f0ceedd2e768b5277", 11:39:24 INFO - "slavename": "talos-linux64-ix-057", 11:39:24 INFO - "repo_path": "releases/mozilla-beta" 11:39:24 INFO - } 11:39:24 INFO - Finding installer, test and symbols from parent task. 11:39:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I6u2zLLrSY6lVfARl8a8VA'}, attempt #1 11:39:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I6u2zLLrSY6lVfARl8a8VA'}, attempt #1 11:39:25 INFO - Task dependencies: Sm_Ke8X4Qbi-vJppj8SwDQ 11:39:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ'}, attempt #1 11:39:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.tar.bz2 11:39:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/test_packages.json 11:39:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.crashreporter-symbols.zip 11:39:25 INFO - Running post-action listener: _resource_record_post_action 11:39:25 INFO - [mozharness: 2017-05-25 18:39:25.981810Z] Finished read-buildbot-config step (success) 11:39:25 INFO - [mozharness: 2017-05-25 18:39:25.982033Z] Running download-and-extract step. 11:39:25 INFO - Running pre-action listener: _resource_record_pre_action 11:39:25 INFO - Running main action method: download_and_extract 11:39:25 INFO - mkdir: /builds/slave/test/build/tests 11:39:25 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')]} 11:39:25 INFO - trying https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.test_packages.json 11:39:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:39:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:39:26 INFO - Downloaded 986 bytes. 11:39:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:39:26 INFO - Using the following test package requirements: 11:39:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:39:26 INFO - u'common': [u'target.common.tests.zip'], 11:39:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:39:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:39:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:39:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:39:26 INFO - u'mozbase': [u'target.common.tests.zip'], 11:39:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:39:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:39:26 INFO - u'web-platform': [u'target.common.tests.zip', 11:39:26 INFO - u'target.web-platform.tests.zip'], 11:39:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:39:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:39:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.common.tests.zip 11:39:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:39:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.common.tests.zip into memory 11:39:27 INFO - Expected file size: 59711963 11:39:27 INFO - Obtained file size: 59711963 11:39:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:39:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.common.tests.zip 11:39:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:39:37 INFO - Fetch https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.common.tests.zip into memory 11:39:38 INFO - Expected file size: 59711963 11:39:38 INFO - Obtained file size: 59711963 11:39:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.talos.tests.zip 11:39:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:39:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.talos.tests.zip into memory 11:39:50 INFO - Expected file size: 14404449 11:39:50 INFO - Obtained file size: 14404449 11:39:50 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')]} 11:39:50 INFO - trying https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.tar.bz2 11:39:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:39:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:39:51 INFO - Downloaded 59759725 bytes. 11:39:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.tar.bz2 11:39:51 INFO - mkdir: /builds/slave/test/properties 11:39:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:39:51 INFO - Writing to file /builds/slave/test/properties/build_url 11:39:51 INFO - Contents: 11:39:51 INFO - build_url:https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.tar.bz2 11:39:51 INFO - Running post-action listener: _resource_record_post_action 11:39:51 INFO - Running post-action listener: set_extra_try_arguments 11:39:51 INFO - [mozharness: 2017-05-25 18:39:51.580478Z] Finished download-and-extract step (success) 11:39:51 INFO - [mozharness: 2017-05-25 18:39:51.580573Z] Running populate-webroot step. 11:39:51 INFO - Running pre-action listener: _resource_record_pre_action 11:39:51 INFO - Running main action method: populate_webroot 11:39:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:39:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:39:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:39:51 INFO - '--tppagecycles', 11:39:51 INFO - '7'], 11:39:51 INFO - 'tests': ['tsvgm']}}, 11:39:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:39:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:39:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:39:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:39:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:39:51 INFO - 'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:39:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:39:51 INFO - 'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['damp', 'tps']}, 11:39:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:39:51 INFO - 'tests': ['damp', 'tps']}, 11:39:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['dromaeo_dom']}, 11:39:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:39:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['basic_compositor_video']}, 11:39:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:39:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['a11yr', 11:39:51 INFO - 'ts_paint', 11:39:51 INFO - 'tpaint', 11:39:51 INFO - 'sessionrestore', 11:39:51 INFO - 'sessionrestore_no_auto_restore', 11:39:51 INFO - 'tabpaint']}, 11:39:51 INFO - 'other-e10s': {'tests': ['a11yr', 11:39:51 INFO - 'ts_paint', 11:39:51 INFO - 'tpaint', 11:39:51 INFO - 'sessionrestore', 11:39:51 INFO - 'sessionrestore_no_auto_restore', 11:39:51 INFO - 'tabpaint']}, 11:39:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:39:51 INFO - 'ts_paint', 11:39:51 INFO - 'tpaint', 11:39:51 INFO - 'sessionrestore', 11:39:51 INFO - 'sessionrestore_no_auto_restore', 11:39:51 INFO - 'tabpaint']}, 11:39:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:39:51 INFO - 'ts_paint', 11:39:51 INFO - 'tpaint', 11:39:51 INFO - 'sessionrestore', 11:39:51 INFO - 'sessionrestore_no_auto_restore', 11:39:51 INFO - 'tabpaint']}, 11:39:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['a11yr', 11:39:51 INFO - 'ts_paint', 11:39:51 INFO - 'tpaint', 11:39:51 INFO - 'sessionrestore', 11:39:51 INFO - 'sessionrestore_no_auto_restore', 11:39:51 INFO - 'tabpaint']}, 11:39:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['a11yr', 11:39:51 INFO - 'ts_paint', 11:39:51 INFO - 'tpaint', 11:39:51 INFO - 'sessionrestore', 11:39:51 INFO - 'sessionrestore_no_auto_restore', 11:39:51 INFO - 'tabpaint']}, 11:39:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['tsvgx', 11:39:51 INFO - 'tsvgr_opacity', 11:39:51 INFO - 'tart', 11:39:51 INFO - 'tscrollx', 11:39:51 INFO - 'cart', 11:39:51 INFO - 'tsvg_static']}, 11:39:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:39:51 INFO - 'tsvgr_opacity', 11:39:51 INFO - 'tart', 11:39:51 INFO - 'tscrollx', 11:39:51 INFO - 'cart', 11:39:51 INFO - 'tsvg_static']}, 11:39:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:39:51 INFO - 'talos_options': ['--disable-e10s'], 11:39:51 INFO - 'tests': ['tp5o']}, 11:39:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:39:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:39:51 INFO - 'talos_options': ['--disable-e10s', 11:39:51 INFO - '--xperf_path', 11:39:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:51 INFO - 'tests': ['tp5n']}, 11:39:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:39:51 INFO - 'talos_options': ['--xperf_path', 11:39:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:51 INFO - 'tests': ['tp5n']}}, 11:39:51 INFO - 'talos.zip': {'path': '', 11:39:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:39:51 INFO - Running post-action listener: _resource_record_post_action 11:39:51 INFO - [mozharness: 2017-05-25 18:39:51.593597Z] Finished populate-webroot step (success) 11:39:51 INFO - [mozharness: 2017-05-25 18:39:51.593687Z] Running create-virtualenv step. 11:39:51 INFO - Running pre-action listener: _resource_record_pre_action 11:39:51 INFO - Running main action method: create_virtualenv 11:39:51 INFO - Creating virtualenv /builds/slave/test/build/venv 11:39:51 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 11:39:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:39:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:39:51 INFO - Using real prefix '/usr' 11:39:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:39:52 INFO - Installing distribute.............................................................................................................................................................................................done. 11:39:54 INFO - Installing pip.................done. 11:39:54 INFO - Return code: 0 11:39:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:39:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:39:54 INFO - Reading from file tmpfile_stdout 11:39:54 INFO - Output received: 11:39:54 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) 11:39:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:39:54 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')]} 11:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:54 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')]} 11:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:54 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 0x28761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b14310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', '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 11:39:54 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 11:39:54 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 11:39:54 INFO - Using env: {'DISPLAY': ':0', 11:39:54 INFO - 'HOME': '/home/cltbld', 11:39:54 INFO - 'LANG': 'en_US.UTF-8', 11:39:54 INFO - 'LANGUAGE': 'en_US:en', 11:39:54 INFO - 'LOGNAME': 'cltbld', 11:39:54 INFO - 'MAIL': '/var/mail/cltbld', 11:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:54 INFO - 'MOZ_NO_REMOTE': '1', 11:39:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:54 INFO - 'NO_EM_RESTART': '1', 11:39:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:54 INFO - 'PWD': '/builds/slave/test', 11:39:54 INFO - 'SHELL': '/bin/bash', 11:39:54 INFO - 'SHLVL': '1', 11:39:54 INFO - 'TERM': 'linux', 11:39:54 INFO - 'TMOUT': '86400', 11:39:54 INFO - 'USER': 'cltbld', 11:39:54 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:54 INFO - '_': '/tools/buildbot/bin/python'} 11:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:54 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 11:39:54 INFO - Cleaning up... 11:39:54 INFO - Return code: 0 11:39:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:39:54 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')]} 11:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:54 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')]} 11:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:54 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 0x28761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b14310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', '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 11:39:54 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 11:39:54 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 11:39:54 INFO - Using env: {'DISPLAY': ':0', 11:39:54 INFO - 'HOME': '/home/cltbld', 11:39:54 INFO - 'LANG': 'en_US.UTF-8', 11:39:54 INFO - 'LANGUAGE': 'en_US:en', 11:39:54 INFO - 'LOGNAME': 'cltbld', 11:39:54 INFO - 'MAIL': '/var/mail/cltbld', 11:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:54 INFO - 'MOZ_NO_REMOTE': '1', 11:39:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:54 INFO - 'NO_EM_RESTART': '1', 11:39:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:54 INFO - 'PWD': '/builds/slave/test', 11:39:54 INFO - 'SHELL': '/bin/bash', 11:39:54 INFO - 'SHLVL': '1', 11:39:54 INFO - 'TERM': 'linux', 11:39:54 INFO - 'TMOUT': '86400', 11:39:54 INFO - 'USER': 'cltbld', 11:39:54 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:54 INFO - '_': '/tools/buildbot/bin/python'} 11:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:54 INFO - Downloading/unpacking psutil>=3.1.1 11:39:54 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 11:39:54 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 11:39:54 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 11:39:54 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 11:39:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:39:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:39:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:39:56 INFO - Installing collected packages: psutil 11:39:56 INFO - Running setup.py install for psutil 11:39:56 INFO - building 'psutil._psutil_linux' extension 11:39:56 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 11:39:56 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 11:39:56 INFO - building 'psutil._psutil_posix' extension 11:39:56 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 11:39:56 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 11:39:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:39:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:39:56 INFO - Successfully installed psutil 11:39:56 INFO - Cleaning up... 11:39:56 INFO - Return code: 0 11:39:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:39:56 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')]} 11:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:56 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')]} 11:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:56 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 0x28761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b14310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', '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 11:39:56 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 11:39:56 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 11:39:56 INFO - Using env: {'DISPLAY': ':0', 11:39:56 INFO - 'HOME': '/home/cltbld', 11:39:56 INFO - 'LANG': 'en_US.UTF-8', 11:39:56 INFO - 'LANGUAGE': 'en_US:en', 11:39:56 INFO - 'LOGNAME': 'cltbld', 11:39:56 INFO - 'MAIL': '/var/mail/cltbld', 11:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:56 INFO - 'MOZ_NO_REMOTE': '1', 11:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:56 INFO - 'NO_EM_RESTART': '1', 11:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:56 INFO - 'PWD': '/builds/slave/test', 11:39:56 INFO - 'SHELL': '/bin/bash', 11:39:56 INFO - 'SHLVL': '1', 11:39:56 INFO - 'TERM': 'linux', 11:39:56 INFO - 'TMOUT': '86400', 11:39:56 INFO - 'USER': 'cltbld', 11:39:56 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:56 INFO - '_': '/tools/buildbot/bin/python'} 11:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:39:56 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 11:39:56 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 11:39:56 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 11:39:56 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 11:39:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:39:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:39:57 INFO - Installing collected packages: mozsystemmonitor 11:39:57 INFO - Running setup.py install for mozsystemmonitor 11:39:57 INFO - Successfully installed mozsystemmonitor 11:39:57 INFO - Cleaning up... 11:39:58 INFO - Return code: 0 11:39:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39: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')]} 11:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:58 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 0x28761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b14310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', '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 11:39:58 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 11:39:58 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 11:39:58 INFO - Using env: {'DISPLAY': ':0', 11:39:58 INFO - 'HOME': '/home/cltbld', 11:39:58 INFO - 'LANG': 'en_US.UTF-8', 11:39:58 INFO - 'LANGUAGE': 'en_US:en', 11:39:58 INFO - 'LOGNAME': 'cltbld', 11:39:58 INFO - 'MAIL': '/var/mail/cltbld', 11:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:58 INFO - 'MOZ_NO_REMOTE': '1', 11:39:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:58 INFO - 'NO_EM_RESTART': '1', 11:39:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:58 INFO - 'PWD': '/builds/slave/test', 11:39:58 INFO - 'SHELL': '/bin/bash', 11:39:58 INFO - 'SHLVL': '1', 11:39:58 INFO - 'TERM': 'linux', 11:39:58 INFO - 'TMOUT': '86400', 11:39:58 INFO - 'USER': 'cltbld', 11:39:58 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:58 INFO - '_': '/tools/buildbot/bin/python'} 11:39:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:58 INFO - Downloading/unpacking jsonschema==2.5.1 11:39:58 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 11:39:58 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 11:39:58 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 11:39:58 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 11:39:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:39:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:39:59 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 11:39:59 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 11:39:59 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 11:39:59 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 11:39:59 INFO - Downloading functools32-3.2.3-2.tar.gz 11:39:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:39:59 INFO - warning: no files found matching '*.txt' 11:39:59 INFO - no previously-included directories found matching 'build' 11:39:59 INFO - no previously-included directories found matching 'dist' 11:39:59 INFO - no previously-included directories found matching '.git*' 11:39:59 INFO - Installing collected packages: jsonschema, functools32 11:39:59 INFO - Running setup.py install for functools32 11:39:59 INFO - warning: no files found matching '*.txt' 11:39:59 INFO - no previously-included directories found matching 'build' 11:39:59 INFO - no previously-included directories found matching 'dist' 11:39:59 INFO - no previously-included directories found matching '.git*' 11:39:59 INFO - Successfully installed jsonschema functools32 11:39:59 INFO - Cleaning up... 11:39:59 INFO - Return code: 0 11:39:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:39:59 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')]} 11:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:59 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')]} 11:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:59 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 0x28761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b14310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', '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 11:39:59 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 11:39:59 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 11:39:59 INFO - Using env: {'DISPLAY': ':0', 11:39:59 INFO - 'HOME': '/home/cltbld', 11:39:59 INFO - 'LANG': 'en_US.UTF-8', 11:39:59 INFO - 'LANGUAGE': 'en_US:en', 11:39:59 INFO - 'LOGNAME': 'cltbld', 11:39:59 INFO - 'MAIL': '/var/mail/cltbld', 11:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:59 INFO - 'MOZ_NO_REMOTE': '1', 11:39:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:59 INFO - 'NO_EM_RESTART': '1', 11:39:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:59 INFO - 'PWD': '/builds/slave/test', 11:39:59 INFO - 'SHELL': '/bin/bash', 11:39:59 INFO - 'SHLVL': '1', 11:39:59 INFO - 'TERM': 'linux', 11:39:59 INFO - 'TMOUT': '86400', 11:39:59 INFO - 'USER': 'cltbld', 11:39:59 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:59 INFO - '_': '/tools/buildbot/bin/python'} 11:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:39:59 INFO - Cleaning up... 11:39:59 INFO - Return code: 0 11:39:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:39:59 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')]} 11:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:59 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')]} 11:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:59 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 0x28761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b14310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', '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 11:39:59 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 11:39:59 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 11:39:59 INFO - Using env: {'DISPLAY': ':0', 11:39:59 INFO - 'HOME': '/home/cltbld', 11:39:59 INFO - 'LANG': 'en_US.UTF-8', 11:39:59 INFO - 'LANGUAGE': 'en_US:en', 11:39:59 INFO - 'LOGNAME': 'cltbld', 11:39:59 INFO - 'MAIL': '/var/mail/cltbld', 11:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:59 INFO - 'MOZ_NO_REMOTE': '1', 11:39:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:39:59 INFO - 'NO_EM_RESTART': '1', 11:39:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:59 INFO - 'PWD': '/builds/slave/test', 11:39:59 INFO - 'SHELL': '/bin/bash', 11:39:59 INFO - 'SHLVL': '1', 11:39:59 INFO - 'TERM': 'linux', 11:39:59 INFO - 'TMOUT': '86400', 11:39:59 INFO - 'USER': 'cltbld', 11:39:59 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:59 INFO - '_': '/tools/buildbot/bin/python'} 11:40:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:00 INFO - Downloading/unpacking blobuploader==1.2.4 11:40:00 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 11:40:00 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 11:40:00 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 11:40:00 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 11:40:01 INFO - Downloading blobuploader-1.2.4.tar.gz 11:40:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:40:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:40:01 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 11:40:01 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 11:40:01 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 11:40:01 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 11:40:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:40:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:40:01 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 11:40:01 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 11:40:01 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 11:40:01 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 11:40:01 INFO - Downloading docopt-0.6.1.tar.gz 11:40:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:40:01 INFO - Installing collected packages: blobuploader, requests, docopt 11:40:01 INFO - Running setup.py install for blobuploader 11:40:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:40:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:40:01 INFO - Running setup.py install for requests 11:40:02 INFO - Running setup.py install for docopt 11:40:02 INFO - Successfully installed blobuploader requests docopt 11:40:02 INFO - Cleaning up... 11:40:02 INFO - Return code: 0 11:40:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:40:02 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')]} 11:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:02 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')]} 11:40:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:02 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 0x28761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b14310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', '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 11:40:02 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 11:40:02 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 11:40:02 INFO - Using env: {'DISPLAY': ':0', 11:40:02 INFO - 'HOME': '/home/cltbld', 11:40:02 INFO - 'LANG': 'en_US.UTF-8', 11:40:02 INFO - 'LANGUAGE': 'en_US:en', 11:40:02 INFO - 'LOGNAME': 'cltbld', 11:40:02 INFO - 'MAIL': '/var/mail/cltbld', 11:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:02 INFO - 'MOZ_NO_REMOTE': '1', 11:40:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:40:02 INFO - 'NO_EM_RESTART': '1', 11:40:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:02 INFO - 'PWD': '/builds/slave/test', 11:40:02 INFO - 'SHELL': '/bin/bash', 11:40:02 INFO - 'SHLVL': '1', 11:40:02 INFO - 'TERM': 'linux', 11:40:02 INFO - 'TMOUT': '86400', 11:40:02 INFO - 'USER': 'cltbld', 11:40:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:02 INFO - '_': '/tools/buildbot/bin/python'} 11:40:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:40:02 INFO - Running setup.py (path:/tmp/pip-RPWTEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:40:02 INFO - Running setup.py (path:/tmp/pip-EqqBAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:40:02 INFO - Running setup.py (path:/tmp/pip-dx2Xgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:40:02 INFO - Running setup.py (path:/tmp/pip-FZRf57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:40:02 INFO - Running setup.py (path:/tmp/pip-N5qfOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:40:02 INFO - Running setup.py (path:/tmp/pip-6IQ9me-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:40:03 INFO - Running setup.py (path:/tmp/pip-xqnCUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:40:03 INFO - Running setup.py (path:/tmp/pip-otWGnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:40:03 INFO - Running setup.py (path:/tmp/pip-DMDpEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:40:03 INFO - Running setup.py (path:/tmp/pip-wqPfj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:40:03 INFO - Running setup.py (path:/tmp/pip-yiJWnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:40:03 INFO - Running setup.py (path:/tmp/pip-GVwHM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:40:03 INFO - Running setup.py (path:/tmp/pip-dlbBOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:40:03 INFO - Running setup.py (path:/tmp/pip-R8Ephi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:40:03 INFO - Running setup.py (path:/tmp/pip-I_Cr8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:40:03 INFO - Running setup.py (path:/tmp/pip-y0Xx9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:40:03 INFO - Running setup.py (path:/tmp/pip-pz2FZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:40:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:40:03 INFO - Running setup.py install for manifestparser 11:40:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:40:03 INFO - Running setup.py install for mozcrash 11:40:04 INFO - Running setup.py install for mozdebug 11:40:04 INFO - Running setup.py install for mozdevice 11:40:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:40:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:40:04 INFO - Running setup.py install for mozfile 11:40:04 INFO - Running setup.py install for mozhttpd 11:40:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:40:04 INFO - Running setup.py install for mozinfo 11:40:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:40:04 INFO - Running setup.py install for mozInstall 11:40:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:40:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:40:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:40:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:40:04 INFO - Running setup.py install for mozleak 11:40:04 INFO - Running setup.py install for mozlog 11:40:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:40:04 INFO - Running setup.py install for moznetwork 11:40:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:40:04 INFO - Running setup.py install for mozprocess 11:40:05 INFO - Running setup.py install for mozprofile 11:40:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:40:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:40:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:40:05 INFO - Running setup.py install for mozrunner 11:40:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:40:05 INFO - Running setup.py install for mozscreenshot 11:40:05 INFO - Running setup.py install for moztest 11:40:05 INFO - Running setup.py install for mozversion 11:40:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:40:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:40:05 INFO - Cleaning up... 11:40:05 INFO - Return code: 0 11:40:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:05 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 0x28761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b14310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', '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 11:40:05 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 11:40:05 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 11:40:05 INFO - Using env: {'DISPLAY': ':0', 11:40:05 INFO - 'HOME': '/home/cltbld', 11:40:05 INFO - 'LANG': 'en_US.UTF-8', 11:40:05 INFO - 'LANGUAGE': 'en_US:en', 11:40:05 INFO - 'LOGNAME': 'cltbld', 11:40:05 INFO - 'MAIL': '/var/mail/cltbld', 11:40:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:05 INFO - 'MOZ_NO_REMOTE': '1', 11:40:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:40:05 INFO - 'NO_EM_RESTART': '1', 11:40:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:40:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:05 INFO - 'PWD': '/builds/slave/test', 11:40:05 INFO - 'SHELL': '/bin/bash', 11:40:05 INFO - 'SHLVL': '1', 11:40:05 INFO - 'TERM': 'linux', 11:40:05 INFO - 'TMOUT': '86400', 11:40:05 INFO - 'USER': 'cltbld', 11:40:05 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:40:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:05 INFO - '_': '/tools/buildbot/bin/python'} 11:40:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:40:05 INFO - Running setup.py (path:/tmp/pip-YsuY1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:40:05 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)) 11:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:40:05 INFO - Running setup.py (path:/tmp/pip-fHSXIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:40:06 INFO - Running setup.py (path:/tmp/pip-8xwgXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:40:06 INFO - Running setup.py (path:/tmp/pip-BhV6uC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:40:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:40:06 INFO - Running setup.py (path:/tmp/pip-fTLpoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:40:06 INFO - Running setup.py (path:/tmp/pip-q1bHty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:40:06 INFO - Running setup.py (path:/tmp/pip-UcTdS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:40:06 INFO - Running setup.py (path:/tmp/pip-qxGVUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:40:06 INFO - Running setup.py (path:/tmp/pip-9iSGFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:40:06 INFO - Running setup.py (path:/tmp/pip-rKrj9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:40:06 INFO - Running setup.py (path:/tmp/pip-tGjdTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:40:06 INFO - Running setup.py (path:/tmp/pip-YcflC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:40:06 INFO - Running setup.py (path:/tmp/pip-i2tj0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:40:06 INFO - Running setup.py (path:/tmp/pip-fCSIgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:40:06 INFO - Running setup.py (path:/tmp/pip-5kzrZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:40:06 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)) 11:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:40:06 INFO - Running setup.py (path:/tmp/pip-PweaRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:40:07 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)) 11:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:40:07 INFO - Running setup.py (path:/tmp/pip-IyAN2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:40:07 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)) 11:40:07 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)) 11:40:07 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)) 11:40:07 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)) 11:40:07 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:40:07 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:40:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:40:07 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 11:40:07 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 11:40:07 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 11:40:07 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 11:40:08 INFO - Downloading blessings-1.6.tar.gz 11:40:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:40:08 INFO - Installing collected packages: blessings 11:40:08 INFO - Running setup.py install for blessings 11:40:08 INFO - Successfully installed blessings 11:40:08 INFO - Cleaning up... 11:40:08 INFO - Return code: 0 11:40:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:40:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:40:08 INFO - Reading from file tmpfile_stdout 11:40:08 INFO - Current package versions: 11:40:08 INFO - argparse == 1.2.1 11:40:08 INFO - blessings == 1.6 11:40:08 INFO - blobuploader == 1.2.4 11:40:08 INFO - docopt == 0.6.1 11:40:08 INFO - functools32 == 3.2.3-2 11:40:08 INFO - jsonschema == 2.5.1 11:40:08 INFO - manifestparser == 1.1 11:40:08 INFO - mozInstall == 1.12 11:40:08 INFO - mozcrash == 1.0 11:40:08 INFO - mozdebug == 0.1 11:40:08 INFO - mozdevice == 0.49 11:40:08 INFO - mozfile == 1.2 11:40:08 INFO - mozhttpd == 0.7 11:40:08 INFO - mozinfo == 0.9 11:40:08 INFO - mozleak == 0.1 11:40:08 INFO - mozlog == 3.4 11:40:08 INFO - moznetwork == 0.27 11:40:08 INFO - mozprocess == 0.25 11:40:08 INFO - mozprofile == 0.28 11:40:08 INFO - mozrunner == 6.13 11:40:08 INFO - mozscreenshot == 0.1 11:40:08 INFO - mozsystemmonitor == 0.3 11:40:08 INFO - moztest == 0.8 11:40:08 INFO - mozversion == 1.4 11:40:08 INFO - psutil == 3.1.1 11:40:08 INFO - requests == 1.2.3 11:40:08 INFO - wsgiref == 0.1.2 11:40:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:08 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 0x28761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b14310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', '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 11:40:08 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 11:40:08 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 11:40:08 INFO - Using env: {'DISPLAY': ':0', 11:40:08 INFO - 'HOME': '/home/cltbld', 11:40:08 INFO - 'LANG': 'en_US.UTF-8', 11:40:08 INFO - 'LANGUAGE': 'en_US:en', 11:40:08 INFO - 'LOGNAME': 'cltbld', 11:40:08 INFO - 'MAIL': '/var/mail/cltbld', 11:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:08 INFO - 'MOZ_NO_REMOTE': '1', 11:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:40:08 INFO - 'NO_EM_RESTART': '1', 11:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:08 INFO - 'PWD': '/builds/slave/test', 11:40:08 INFO - 'SHELL': '/bin/bash', 11:40:08 INFO - 'SHLVL': '1', 11:40:08 INFO - 'TERM': 'linux', 11:40:08 INFO - 'TMOUT': '86400', 11:40:08 INFO - 'USER': 'cltbld', 11:40:08 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:08 INFO - '_': '/tools/buildbot/bin/python'} 11:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:08 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)) 11:40:08 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)) 11:40:08 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)) 11:40:08 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)) 11:40:08 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)) 11:40:08 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)) 11:40:08 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)) 11:40:08 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)) 11:40:08 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)) 11:40:08 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)) 11:40:08 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)) 11:40:08 INFO - Cleaning up... 11:40:08 INFO - Return code: 0 11:40:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b14310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, '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': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', '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 11:40:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:40:08 INFO - Using env: {'DISPLAY': ':0', 11:40:08 INFO - 'HOME': '/home/cltbld', 11:40:08 INFO - 'LANG': 'en_US.UTF-8', 11:40:08 INFO - 'LANGUAGE': 'en_US:en', 11:40:08 INFO - 'LOGNAME': 'cltbld', 11:40:08 INFO - 'MAIL': '/var/mail/cltbld', 11:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:08 INFO - 'MOZ_NO_REMOTE': '1', 11:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:40:08 INFO - 'NO_EM_RESTART': '1', 11:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:08 INFO - 'PWD': '/builds/slave/test', 11:40:08 INFO - 'SHELL': '/bin/bash', 11:40:08 INFO - 'SHLVL': '1', 11:40:08 INFO - 'TERM': 'linux', 11:40:08 INFO - 'TMOUT': '86400', 11:40:08 INFO - 'USER': 'cltbld', 11:40:08 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:08 INFO - '_': '/tools/buildbot/bin/python'} 11:40:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:40:09 INFO - Cleaning up... 11:40:09 INFO - Return code: 0 11:40:09 INFO - Running post-action listener: _resource_record_post_action 11:40:09 INFO - Running post-action listener: _start_resource_monitoring 11:40:09 INFO - Starting resource monitoring. 11:40:09 INFO - [mozharness: 2017-05-25 18:40:09.172108Z] Finished create-virtualenv step (success) 11:40:09 INFO - [mozharness: 2017-05-25 18:40:09.172579Z] Running install step. 11:40:09 INFO - Running pre-action listener: _resource_record_pre_action 11:40:09 INFO - Running main action method: install 11:40:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:40:09 INFO - Reading from file tmpfile_stdout 11:40:09 INFO - Detecting whether we're running mozinstall >=1.0... 11:40:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:40:09 INFO - Reading from file tmpfile_stdout 11:40:09 INFO - Output received: 11:40:09 INFO - Usage: mozinstall [options] installer 11:40:09 INFO - Options: 11:40:09 INFO - -h, --help show this help message and exit 11:40:09 INFO - -d DEST, --destination=DEST 11:40:09 INFO - Directory to install application into. [default: 11:40:09 INFO - "/builds/slave/test"] 11:40:09 INFO - --app=APP Application being installed. [default: firefox] 11:40:09 INFO - mkdir: /builds/slave/test/build/application 11:40:09 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'] 11:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:40:23 INFO - Reading from file tmpfile_stdout 11:40:23 INFO - Output received: 11:40:23 INFO - /builds/slave/test/build/application/firefox/firefox 11:40:23 INFO - Running post-action listener: _resource_record_post_action 11:40:23 INFO - [mozharness: 2017-05-25 18:40:23.564507Z] Finished install step (success) 11:40:23 INFO - [mozharness: 2017-05-25 18:40:23.564748Z] Running run-tests step. 11:40:23 INFO - Running pre-action listener: _resource_record_pre_action 11:40:23 INFO - Running main action method: run_tests 11:40:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:40:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:40:23 INFO - Python 2.7.3 11:40:23 INFO - Return code: 0 11:40:23 INFO - grabbing minidump binary from tooltool 11:40:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:23 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 0x2b11400>, '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 0x2b123b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b12840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:40:23 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 11:40:23 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 11:40:23 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 11:40:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:40:23 INFO - Return code: 0 11:40:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:40:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:40:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:40:23 INFO - ENV: RUST_BACKTRACE is now 1 11:40:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:40:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:40:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 11:40:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --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 11:40:23 INFO - Using env: {'DISPLAY': ':0', 11:40:23 INFO - 'HOME': '/home/cltbld', 11:40:23 INFO - 'LANG': 'en_US.UTF-8', 11:40:23 INFO - 'LANGUAGE': 'en_US:en', 11:40:23 INFO - 'LOGNAME': 'cltbld', 11:40:23 INFO - 'MAIL': '/var/mail/cltbld', 11:40:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:40:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:40:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:23 INFO - 'MOZ_NO_REMOTE': '1', 11:40:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:40:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:40:23 INFO - 'NO_EM_RESTART': '1', 11:40:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:40:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:23 INFO - 'PWD': '/builds/slave/test', 11:40:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:40:23 INFO - 'RUST_BACKTRACE': '1', 11:40:23 INFO - 'SHELL': '/bin/bash', 11:40:23 INFO - 'SHLVL': '1', 11:40:23 INFO - 'TERM': 'linux', 11:40:23 INFO - 'TMOUT': '86400', 11:40:23 INFO - 'USER': 'cltbld', 11:40:23 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968', 11:40:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:23 INFO - '_': '/tools/buildbot/bin/python'} 11:40:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 11:40:23 INFO - mozversion application_buildid: 20170525163428 11:40:23 INFO - mozversion application_changeset: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 11:40:23 INFO - mozversion application_display_name: Firefox 11:40:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:40:23 INFO - mozversion application_name: Firefox 11:40:23 INFO - mozversion application_remotingname: firefox 11:40:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:40:23 INFO - mozversion application_vendor: Mozilla 11:40:23 INFO - mozversion application_version: 54.0 11:40:23 INFO - mozversion platform_buildid: 20170525163428 11:40:23 INFO - mozversion platform_changeset: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 11:40:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:40:23 INFO - mozversion platform_version: 54.0 11:40:23 INFO - using testdate: 1495737623 11:40:23 INFO - actual date: 1495737623 11:40:23 INFO - starting webserver on 'localhost:45226' 11:40:23 INFO - SUITE-START | Running 2 tests 11:40:23 INFO - TEST-START | dromaeo_css 11:40:23 INFO - operating with platform_type : linux_ 11:40:23 INFO - Initialising browser for dromaeo_css test... 11:40:23 INFO - TEST-INFO | started process 15442 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNhy8l0/profile http://localhost:45226/getInfo.html) 11:40:28 INFO - PROCESS | 15442 | __metrics Screen width/height:1600/1200 11:40:28 INFO - PROCESS | 15442 | colorDepth:24 11:40:28 INFO - PROCESS | 15442 | Browser inner width/height: 1024/697 11:40:28 INFO - PROCESS | 15442 | __metrics 11:40:29 INFO - PROCESS | 15442 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:40:29 INFO - PROCESS | 15442 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:40:29 INFO - PROCESS | 15442 | console.error: PushService: 11:40:29 INFO - PROCESS | 15442 | stateChangeProcessEnqueue: Error transitioning state 11:40:29 INFO - PROCESS | 15442 | UnknownError 11:40:30 INFO - TEST-INFO | 15442: exit 0 11:40:30 INFO - Browser initialized. 11:40:30 INFO - Running cycle 1/1 for dromaeo_css test... 11:40:30 INFO - TEST-INFO | started process 15534 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNhy8l0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:40:42 INFO - PROCESS | 15534 | RSS: Main: 141262848 11:40:42 INFO - PROCESS | 15534 | 11:43:43 INFO - PROCESS | 15534 | Cycle 1(1): loaded http://localhost:45226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45226/tests/dromaeo/cssquery-ext.html) 11:43:45 INFO - PROCESS | 15534 | RSS: Main: 283148288 11:43:45 INFO - PROCESS | 15534 | 11:46:47 INFO - PROCESS | 15534 | Cycle 1(1): loaded http://localhost:45226/tests/dromaeo/cssquery-ext.html (next: http://localhost:45226/tests/dromaeo/cssquery-jquery.html) 11:46:47 INFO - PROCESS | 15534 | RSS: Main: 225955840 11:46:47 INFO - PROCESS | 15534 | 11:49:49 INFO - PROCESS | 15534 | Cycle 1(1): loaded http://localhost:45226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45226/tests/dromaeo/cssquery-mootools.html) 11:49:50 INFO - PROCESS | 15534 | RSS: Main: 172040192 11:49:50 INFO - PROCESS | 15534 | 11:52:52 INFO - PROCESS | 15534 | Cycle 1(1): loaded http://localhost:45226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45226/tests/dromaeo/cssquery-prototype.html) 11:52:52 INFO - PROCESS | 15534 | RSS: Main: 170930176 11:52:52 INFO - PROCESS | 15534 | 11:55:55 INFO - PROCESS | 15534 | Cycle 1(1): loaded http://localhost:45226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45226/tests/dromaeo/cssquery-yui.html) 11:55:56 INFO - PROCESS | 15534 | RSS: Main: 175439872 11:55:56 INFO - PROCESS | 15534 | 11:58:58 INFO - PROCESS | 15534 | Cycle 1(1): loaded http://localhost:45226/tests/dromaeo/cssquery-yui.html (next: http://localhost:45226/tests/dromaeo/cssquery-dojo.html) 11:58:58 INFO - PROCESS | 15534 | RSS: Main: 267124736 11:58:58 INFO - PROCESS | 15534 | 11:58:58 INFO - PROCESS | 15534 | __start_tp_report 11:58:58 INFO - PROCESS | 15534 | _x_x_mozilla_page_load 11:58:58 INFO - PROCESS | 15534 | _x_x_mozilla_page_load_details 11:58:58 INFO - PROCESS | 15534 | |i|pagename|runs| 11:58:58 INFO - PROCESS | 15534 | |0;dojo.html;3384.3569721752865;3451.775634583752;3463.2554000889804;3478.217401084755;3503.5795704515444;5798.217240672508;5823.388544202861;5876.382979787119;5882.911756323648;5886.087656413258;5824.650520968745;5870.61840980336;5878.23582934219;5892.528597712183;5894.675792831404;5339.22581225722;5342.572594192465;5355.651882627617;5385.649932754378;5456.48163424476;5165.328507294065;5427.375851777037;5438.592105592076;5446.673199608016;5454.072807622694;5196.532312091911;5272.789088436458;5328.333958255218;5329.493698098694;5341.172118321647;5388.838334849961;5423.810166644149;5427.891442171134;5428.891422171554;5432.70120143392;5318.574514038906;5329.546988505982;5349;5370.087085195526;5392.299001129828;5604.8038318659055;5649.915251271234;5749.0514065179195;5805.5065319447895;5812.970935145339;6295.150154729145;6306.274778400497;6307.810765677037;6315.557910946236;6318.43134117927;6363.204599425072;6363.7772677956045;6364.395382438661;6371.203599550056;6371.904421433682;5651.2935883014625;5691.829245122694;5787.8553036174435;5793.304803423616;5911.083782013795;6314.336994615591;6364.299927008025;6367.681615919186;6369.617822930546;6432.742690292336;6127.877442451126;6177.938220617826;6194.5044396448175;6194.628322300677;6195.31851496335;6062.393760623991;6095.786647467317;6143.511877689543;6260.154879091356;6262.906056409158;6264.436200741955;6271.372862713783;6281.3404592518045;6285.057241413824;6291.6854157291955;5956.7319470623925;5997.910031349533;5998.730057147439;6291.339409362043;6299;4650.209464391061;4695.929561056587;4705.082508910729;4709.1994360958715;4732.100900828831;4184.602462766033;4196.475440569929;4297.742135471882;4307.483102027777;4335.7615331157085;5879.265091863517;5892.175095486636;5893.970530147321;5899.439553242435;5935.881282374415;6277.24673039229;6311.873762524724;6352.983522636356;6353.904691429632;6358.427741503287;5816.41835816415;5998.310194327621;6010.459058684739;6018.8194354169445;6053.424924632153;5295.391030031596;5314.707691077027;5344.946550534445;5391;5401.270828438111;6562.737490500326;6586.703598338182;6600.174978127734;6603.405693487513;6604.768833090915;6274.247090349188;6312.5581209314905;6320.051992201023;6326.778562750282;6331.145295385249;6312.873742525032;6347.936520634919;6427.646479443675;6479.254885688206;6580.309067547839;6209.130721699056;6212.099245609271;6212.347703491068;6214.40963108504;6220.129181914445;6270.529710271839;6280.874382512233;6281.089242059785;6282.994720844643;6309.148264984168;5885.205497257815;5910.231669882888;6063.514918806397;6080.087986802015;6105.45050945417;6120.693965301806;6126.700892189639;6135.110179431545;6144.631322120687;6144.692765361803;6124.652576433177;6131.141640170291;6135.11040899076;6155.076738489083;6157.384261573807;5894.115882617471;5940.049592065248;5978.790742324171;6108.938910611015;6128.938710612837;6061.060535616986;6061.121137435135;6066.575339726353;6068.484178844671;6090.086487026804 11:58:58 INFO - PROCESS | 15534 | |1;ext.html;760.1790066727934;841.8274253777975;854.5769843927255;905.5064989580678;906.9773255668612;33067.669323306756;44941.325880111784;45988.770056149755;46054.309185362246;46082.539174608246;43027.13945721084;43212.35181472272;43315.78342108293;46784.53215467844;47020.29469557959;7617.695292188306;7805.804854878616;9158.679446219383;9279.907200928006;9296.163345298923;10557.260991730582;10870.891291087068;11213.831792523119;11214.887851121506;11218.27081239721;10977.83533246998;10982.286151400174;11033.172512061587;11043.171762117832;11045.668629941074;10901.236913416064;10919.39943303118;10931.617393391272;10937.234393592451;10942.343459392383;10687.839682404692;10756.784864302748;10759;10764.407957562331;10765.461726913702;44098.77950610226;44164.11671766579;44179.77910110429;44301;44324.77837610824;4310.978445107786;4378.540253273392;4386.363977223316;4472.9776351118035;4506.549345065468;31221.53167702486;31549.684503154906;32031.839840800647;32238.838805806055;33186.50220246698;4346.869593912188;4367.148406060819;4405.625521830616;4419.513853476115;4464.709793863389;241238;242457;243183;244412;244777;55896;56038.439615603325;56303.34028288148;56333;56352.15471767927;57963.71018144882;58101.418985810444;58230.417695823344;58256.834863302494;58338.70830645905;17342.653146937242;17407.65184696299;17410.477685669197;17440.912795435943;17442.65114697699;13325.333733313295;13561.728765424636;14880.479183228535;15458.845411545966;15464.14947177916;14047.578572642837;14119.43522259098;14134.36395362211;14300.785488217685;14337.64155896111;5581.553475721932;5588.91616625751;5617.859553511195;5640.210370548127;5643.633163844337;1006.4263369879098;1006.8439391894268;1011.145581983223;1014.1785154025263;1015.7206768138673;29097.85451072731;29271.268218294714;29372.559411608843;29671.406571868443;29803.701962980525;1078.9730256743644;1086.9565217391216;1092.1317552545745;1093.578972095749;1101.531848964187;14836.554903352917;15712.371505139896;15732.60668483297;15802.36790528366;15823;327314;327964;330183;330228;330806;59801.700991495636;59949.10076348771;59950;59960;60050.699746500984;1469.8897582681127;1499.7225513280077;1521.634807646157;1526.778617100514;1534.7851300817895;28593.857030714713;30054.84972575123;30153.24616884508;30296.697033029388;30733.538996914616;38467.2306553873;38524.80737596294;38575.6142438572;38621.42067868928;38654.42018369783;303727;304286;305289;305327;306814;25091.24726258215;26121;27427.177184684493;31555.526667100472;31562.68437315689;32316;33335;33504.66495335113;39516.60483395129;39770.403443947784;30296.242593935327;30969.380612388075;31227.531587026668;32101.518477222395;33563.32873342471;32494.187645309055;32570.185745356557;32662.51006234956;32746.836265818518;32999.67000329966;37102.25795484129;37439.251214975004;37503.43744843775;37762.622373775914;38370.42444363393;32036.6796332043;32293.192670183435;32352.83823580867;32559.674403255663;33052;25010.3747406309;25677;25689.486210276064;25990.35024124412;26285.737142629096 11:58:58 INFO - PROCESS | 15534 | |2;jquery.html;471.73582793635586;476.88077980504875;482.7706839251356;486.3871521882427;497.1151350595939;19953.800461995375;20031.799682003177;21617.67573486399;21866.125354985783;21898.23356182534;22589.435264118405;22646;22855.085796568157;23139.884300578473;23203.071877124938;6562.770303039395;6659.234188068385;6675.332466753334;6731.495137864656;6826.897596536056;6637.800865974004;6747.224069232027;6752.763653272136;6753.8986915196065;6756.594604323732;6541.116949211867;6801.829954251134;6832.2826103259185;6845;6853.143357080365;6528.543001989862;6684.832879178035;6696.096027036337;6748.831279218034;6792.422644075259;6536.640484773336;6546.378094081102;6547.050677651761;6549.083128362033;6554.082428460019;19127.33054343091;19426.514337141543;25715;25945.22164335073;25946.351341216432;3139.748820094387;3147.512135618983;3233.2563510392743;3462.515247865301;3558.946615800765;15637.296321665552;15850.128242946634;16006;16967.915160424243;17284;2228.9219877304376;2280.6350983842503;2330.102910379499;2375.881205939696;2556.437583731576;117894.4105279468;119946.4002679981;122753;123064.38467807604;123196.38401808024;18253.17860696272;18412;18586.44240672782;18607;18683.71974420385;32677.50983735246;32917.835410823274;33064.3387132256;33092.503612445835;33137.66862331346;10802.675919722264;10844.566217351376;11037.668869933916;11040.613578524713;11314;9352.205062569758;9369.70911549979;9509.524523773784;9636.662716804878;9637.4217546946;9016.979311930576;9310.953445232866;9434;9446.574904129331;9674.56464459101;3164.905052848418;3846;3863.787491687984;3878.825452854628;4054.412110243998;960.8174446855076;961.245422343457;980.6910823090847;981.1611072532882;983.1643103362228;20801.271955481487;21048.579028419652;21139.26012589552;21192.576148476943;21236.575268494544;982.2240430060315;991.5389343955095;995.144176008632;996.7508122969258;999.0758548342753;11061.723456913642;11654.76690466186;11724.413779311171;11777.470013849397;11944.940275298568;652046;661994;680666;686215;688799;108889.45555272173;109195;111514.44242778735;111824.88175118144;112353;1222.113967373647;1226.2519862883635;1261.9495220191272;1269.346286662351;1270.5934101087748;17691.73462398091;17890.463286101436;18125.637487250446;18319.908400457913;18435.170417330984;48123.5187648119;48684;48713.26930095981;48758.75620621874;49344.25983610321;65206.67396662986;65570.34429655834;66046;67274.32725672876;68435;11611.361375124448;11816.291022538675;11821.40892955366;11922.880771192176;12183.329916854304;12846.67883302925;13347.332633368098;13415.731685366043;13566.321683915961;13682.657933551742;10550.630727924487;10615.946920265349;11302.208845380745;11334.829977550507;12026.879731202576;11400.600978965696;11611.419429028347;11612.6516204514;11618.76762464763;12149;11595.246308989888;11646.126540509325;12639.241645501299;12840.807387889003;13233.735325293248;11785.410729463663;11855.288682679067;11857;11887.702807429883;12070.939645301718;10896.50965706561;10953.233273670767;10986.285891417032;11436.82844757346;12468.438920248429 11:58:58 INFO - PROCESS | 15534 | |3;mootools.html;1590.9920450397747;1593.4821183115491;1620.9594760130994;1621.0678859655698;1633.8529532342095;10992;11739.354335511543;11781.528738850435;11787.999999999989;11792.941035294833;11092.223544351898;11378.2035257532;11397.886021139786;11408.315501069941;11482.081433485322;2551.1453663022908;2585.741425857418;2595.416031392931;2595.9351016224555;2600.7399260073935;2431.671724317221;2497.9375515612073;2525.9242222733255;2529.064246228898;2533.3160046787357;2450.4608986023045;2506.8119891008155;2519;2534.8986040558448;2535.378832186117;2415.903363865443;2475.084218839023;2482.6027835546406;2483.1557270528015;2497.126005897939;2430.501747141832;2439.9634005489934;2455.0302630461047;2456.0421435640114;2456.0421435640114;11402.71493212668;11546.249493782962;11548.711282218012;11561.248518846332;11562;5948.167256584082;6071.1803906472505;6079.544034197453;6088.025915853486;6107.297660769024;3477.643718949612;3615.4215325547784;3620.3664358737115;3671.0822294426393;3696.2977034363653;1423.6298562373754;2262.196920093376;2302.7466978632337;2312.8034117100065;2335;15123.697526049418;15537.533873983686;17050.65898682032;17888.195031223495;17952.281908723635;4809.182438985381;4906.1414252505665;4974.403071631427;4987.850364489072;5018.774155163026;13207.339633018311;13230.272335021473;13233.933830330787;13239.139455935334;13257.204567725967;6093.086037094471;6124.387561243929;6127.479164270998;6127.571070025145;6129.662868542273;5474.808381706621;5618.971905140448;5648.152777083388;5672.064109421982;5709.143628455765;3982.9402558961638;4061.634452899194;4078.6737061035046;4121.505419349697;4144.150449157915;1060.7136073260176;1072.3030030480095;1073.2701762801214;1087.6465148826662;1088.5101704232968;1107.7562936154034;1122.4107343644653;1133.5975728616388;1150.9654710358702;1175.8118701007797;7307.1231452226075;7450.441216908754;7511.173770885198;7521.962390188014;7615.581143037186;1053.2153545608417;1060.8355704865758;1065.541817018674;1073.8013467508536;1111.6331610568493;4819.277108433763;4826.493218212107;4919.893024069627;4942.135126352874;5099.413567439718;14319.35562899672;15381.154036528098;15439.459618913284;15440.382384704712;15445.922770386302;13795.655108622364;14239.288035598385;14258.643533911736;14294.928525357514;15153.469628563167;8941.418807777343;10760.139188864872;10941.726456838484;10965.671029869116;11031.062359699516;6288.516036165597;7299.160596531467;7514.887276690744;7734.342580880463;7777.650005749868;12443.37783110859;12679.556215532411;12746.298953557643;12791.296478693761;12944.8058279124;12699;12795.296258705488;13070.477180912658;13157.407916643582;13221.603351899459;9925.205983521446;10129.544170512198;10164.237682174014;10194.388336699822;10304.124149447382;11073.5570577176;11082.501287441923;11092.556297748322;11191.720206994565;11259.155563332619;9255.490947997656;9293.814123717353;9340.673076442292;9516.85724714116;9521.381110227812;9078.31912606544;9149.63401463934;9215.86176207344;9347;9370.812583748151;8472.406931514735;8486.830263394573;8496.32029437656;8547.145285471403;8551.786205344915;6623.4038936495945;6680.6993685283305;6706.765263215764;6720.529562930548;6769.864602707819;6286.245650521967;6306.747730090745;6314.210723659543;6640.435562977201;6658.467322614276 11:58:58 INFO - PROCESS | 15534 | |4;prototype.html;422.57953336430245;440.41424904876516;448.68816172759944;451.45599552539215;454.10767841192074;5284.357821089456;5322.467753224676;5339.9708110917845;5346.866328341794;5368.677879327243;5195.922061169085;5205.65745414563;5219.217117432387;5224.608154388427;5230.6600070995355;4324.264874971254;4466.731996080245;4583.381243532131;4587.678862479628;4617.699849509788;4548.74909399916;4564.566366195223;4595.448546174463;4605.424321959755;4618.307253911907;4589.816407343719;4609.884752881188;4620.861374158781;4623.237165867645;4629.93055104172;4439.1121775645;4553.726776393427;4579.610733087658;4597.977010114962;4625.676202665798;4374.715643483195;4379.977815307428;4392.407025051609;4413.249747542925;4421;5090.720010399426;5112.488751124895;5159.2003239497935;5204.635675502716;5254.500822421864;5116.923246151311;5274.182501712241;5292.735363231823;5310.291751206873;5332.546733527654;5518.503334699896;5543.944560554423;5589.7205139743255;5612.69130197839;5620.971895140539;4937.530934561211;4939.629527785431;4945.887175385546;4964.553190212862;5001.174806157016;5467.589930755209;5583.469570390806;5655.8586035349035;5727.140928860663;5810.302763668345;4994.950050499521;5036.269740887551;5055.9494405055475;5093.337866077386;5093.643444958891;5134.332536770271;5162;5162.6902385856965;5174.793008279702;5200.947990520122;5209.218617207449;5310.893782124336;5350.946490535122;5351.224072509464;5381.152475979693;5600.243967064475;5602.943970560324;5606.682429629034;5618.522425593788;5621.133222056698;1806.2323512507132;1814.2561549764798;1826.3972888946616;1827.2234300422267;1837.4120281509788;1413.300416293941;1440.5678296511005;1478.7042591481531;1482.495951376537;1491.5376233367474;5370.9731451342495;5385.353757549041;5393.191021346829;5395.6798576611645;5438.15708565173;5639.3232812062;5656.6888821115235;5673.3475650299915;5676.545876329884;5686.658800471985;5129.820456284012;5387.946120538744;5412.648177868426;5414.702191379433;5448.564114870801;2974.6500264791007;2985.9701402986125;2991.880324786984;3004.744596709304;3008.849557522115;5869.2956845178;5916.970415147896;5917.786853694982;5930.347661757203;5948.940510594925;5498.6630681960005;5740.282464691913;5747.666353514951;5751.654900705971;5790.855228619234;5846.239988801429;5850.5612079094235;5856.385079566755;5867.970660146757;5871.64770113786;5395.81114660985;5563.554915606661;5658.830235092953;5694.857628559319;5696.971515142398;5390.380106287827;5623.915641265303;5630.915536267041;5652.236948012131;5677.318721753416;5752.597318187687;5786.495511167042;5917.3490915998345;5931.377205393372;5946;4784.138855006132;4797.4482934462985;4797.760111994457;4840.249761287007;4856.223004319292;5473.31583552056;5616.466435688603;5658.85852853682;5665.773369065191;5841.283803031611;5481.397046324981;5543.52880005204;5549.724612908688;5559.944400555943;5560.805371811967;5496.203050557567;5511.035568775368;5542.72286385671;5544.223808666709;5618.915716264178;5315.973420132875;5515.200295957144;5524.917126243029;5531.502164805187;5531.585131115263;5521.751521181637;5568.359638641608;5618.410066942912;5631.605787594829;5640.080144299673;5376.8118115865755;5468.484497131205;5526.613137080366;5534.363548191848;5572.86067848307 11:58:58 INFO - PROCESS | 15534 | |5;yui.html;794.479615851617;813.3696385301395;881.7487016200382;937.081659973226;945.6359301668859;16223.918880405581;16506.75239871403;17071.146442677848;17321.566960825985;18778.62442751146;16680.082595457276;16827.15864206788;16959.236834342453;16993.575160620992;17293.740593891103;886.2156991062907;911.0661571888801;913.8857642794651;914.0676509959837;914.2229105260509;869.8260347930408;885.4023534114447;888.6312180445108;889.7864512517014;890.9198172164537;934.2059249637822;938.1509733690987;938.5823308627663;938.7700013496669;939.5208443693697;932.4591736792712;935.7192842147329;937.6015193542717;937.6483818568003;937.7186843946789;685.6160550091914;687.7489716253582;688.7933619914056;689.0628744906923;689.4381079420257;17572;17587.03271320077;17693;19331.710024349646;19609.31367402134;15989.440369587008;16292;16597.751033734505;16731.414400496047;16864.747028794576;13221.933890330583;13380.063395562314;13418.127821691656;13508.257045862474;13574.253416062113;3855.7108216883844;3867.7099217558516;3899.3020937188435;3915.393114067324;3996.6403023728003;70431.64784176047;92424.53787731085;92725;93148;94091.52954235254;12356.25862448256;12534.498620055096;12544.121911466109;12639.747205055848;12656.683582910317;24681.382965426008;24764.38089047788;24835.25494235176;24867.751322486907;25070.247892562886;9020.774480637905;9038.954805225932;9040.728778136667;9042.773930651654;9068.637254509746;5053;5058.519440653132;5105.846824595268;5127.589792816566;5195.220716892496;3692.8892133236045;3747.38168202249;3774.7735135891385;3778.4332350147697;3783.5459744830246;585.0492948957943;586.9148973398833;590.2503820148473;597.0685730260807;601.4106175947509;640.5068097564942;641.5348872067807;646.9385408386196;653.4870126950321;656.3567703650353;11194.888051119384;11389.772204555862;11682.88317116818;11846.822297665542;12154.270743755225;761.2539711083059;769.3114662377141;770.2220757035323;773.4856320546886;778.9805254868674;5221.190715439113;5487.6158668892795;5539.944600554023;5587.608867379326;5600.4399560044485;76528.61735691286;79494;79890;81685.59157204175;86619.56690216508;66060;66126;67050;67271.66364168246;67970;51091.48908510942;61720;62282;62454;62458;11756.353400563052;12016.939915300367;12062.819057713965;12237.938810305892;12354.876451235732;42126.7893660542;42423.15153696847;43000.56999429966;43296.78351608222;43540.78229608832;50270.49729502804;50411;50545.494545054076;50552.49447505478;52346.476535234164;13823.01016982844;13957.790633140714;14037.087589306662;14193.574192774233;14330;16491.752623710894;16819.579510512336;17562.473125806246;17749.911250443663;17833.73249401286;14986.775198372252;15159.393624255348;15414.92292538575;16237.51287461378;16274.755878662067;15582.454614088452;15733.842661573237;16197.109158995898;16445.09551974605;16453.259603318118;13744.587662370144;13886.444542218198;13959.371828267549;14037;14246.857531424554;11680.299182049104;12053.57812476559;12186.086043546593;12286.447109880255;12382.442790074649;12821.743565128458;12834.614961551168;13030.869691303345;13174.407151678006;13241.536546220836 11:58:58 INFO - PROCESS | 15534 | __end_tp_report 11:58:58 INFO - PROCESS | 15534 | __start_cc_report 11:58:58 INFO - PROCESS | 15534 | _x_x_mozilla_cycle_collect,1416 11:58:58 INFO - PROCESS | 15534 | __end_cc_report 11:58:58 INFO - PROCESS | 15534 | __startTimestamp1495738738725__endTimestamp 11:58:58 INFO - PROCESS | 15534 | 11:58:58 INFO - PROCESS | 15534 | ------- Summary: start ------- 11:58:58 INFO - PROCESS | 15534 | Number of tests: 6 11:58:58 INFO - PROCESS | 15534 | 11:58:58 INFO - PROCESS | 15534 | [#0] dojo.html Cycles:165 Average:5818.08 Median:6053.42 stddev:659.02 (10.9%) stddev-sans-first:632.78 11:58:58 INFO - PROCESS | 15534 | Values: 3384.4 3451.8 3463.3 3478.2 3503.6 5798.2 5823.4 5876.4 5882.9 5886.1 5824.7 5870.6 5878.2 5892.5 5894.7 5339.2 5342.6 5355.7 5385.6 5456.5 5165.3 5427.4 5438.6 5446.7 5454.1 5196.5 5272.8 5328.3 5329.5 5341.2 5388.8 5423.8 5427.9 5428.9 5432.7 5318.6 5329.5 5349.0 5370.1 5392.3 5604.8 5649.9 5749.1 5805.5 5813.0 6295.2 6306.3 6307.8 6315.6 6318.4 6363.2 6363.8 6364.4 6371.2 6371.9 5651.3 5691.8 5787.9 5793.3 5911.1 6314.3 6364.3 6367.7 6369.6 6432.7 6127.9 6177.9 6194.5 6194.6 6195.3 6062.4 6095.8 6143.5 6260.2 6262.9 6264.4 6271.4 6281.3 6285.1 6291.7 5956.7 5997.9 5998.7 6291.3 6299.0 4650.2 4695.9 4705.1 4709.2 4732.1 4184.6 4196.5 4297.7 4307.5 4335.8 5879.3 5892.2 5894.0 5899.4 5935.9 6277.2 6311.9 6353.0 6353.9 6358.4 5816.4 5998.3 6010.5 6018.8 6053.4 5295.4 5314.7 5344.9 5391.0 5401.3 6562.7 6586.7 6600.2 6603.4 6604.8 6274.2 6312.6 6320.1 6326.8 6331.1 6312.9 6347.9 6427.6 6479.3 6580.3 6209.1 6212.1 6212.3 6214.4 6220.1 6270.5 6280.9 6281.1 6283.0 6309.1 5885.2 5910.2 6063.5 6080.1 6105.5 6120.7 6126.7 6135.1 6144.6 6144.7 6124.7 6131.1 6135.1 6155.1 6157.4 5894.1 5940.0 5978.8 6108.9 6128.9 6061.1 6061.1 6066.6 6068.5 6090.1 11:58:58 INFO - PROCESS | 15534 | 11:58:58 INFO - PROCESS | 15534 | [#1] ext.html Cycles:180 Average:46421.64 Median:32034.26 stddev:76997.68 (240.4%) stddev-sans-first:77137.36 11:58:58 INFO - PROCESS | 15534 | Values: 760.2 841.8 854.6 905.5 907.0 33067.7 44941.3 45988.8 46054.3 46082.5 43027.1 43212.4 43315.8 46784.5 47020.3 7617.7 7805.8 9158.7 9279.9 9296.2 10557.3 10870.9 11213.8 11214.9 11218.3 10977.8 10982.3 11033.2 11043.2 11045.7 10901.2 10919.4 10931.6 10937.2 10942.3 10687.8 10756.8 10759.0 10764.4 10765.5 44098.8 44164.1 44179.8 44301.0 44324.8 4311.0 4378.5 4386.4 4473.0 4506.5 31221.5 31549.7 32031.8 32238.8 33186.5 4346.9 4367.1 4405.6 4419.5 4464.7 241238.0 242457.0 243183.0 244412.0 244777.0 55896.0 56038.4 56303.3 56333.0 56352.2 57963.7 58101.4 58230.4 58256.8 58338.7 17342.7 17407.7 17410.5 17440.9 17442.7 13325.3 13561.7 14880.5 15458.8 15464.1 14047.6 14119.4 14134.4 14300.8 14337.6 5581.6 5588.9 5617.9 5640.2 5643.6 1006.4 1006.8 1011.1 1014.2 1015.7 29097.9 29271.3 29372.6 29671.4 29803.7 1079.0 1087.0 1092.1 1093.6 1101.5 14836.6 15712.4 15732.6 15802.4 15823.0 327314.0 327964.0 330183.0 330228.0 330806.0 59801.7 59949.1 59950.0 59960.0 60050.7 1469.9 1499.7 1521.6 1526.8 1534.8 28593.9 30054.8 30153.2 30296.7 30733.5 38467.2 38524.8 38575.6 38621.4 38654.4 303727.0 304286.0 305289.0 305327.0 306814.0 25091.2 26121.0 27427.2 31555.5 31562.7 32316.0 33335.0 33504.7 39516.6 39770.4 30296.2 30969.4 31227.5 32101.5 33563.3 32494.2 32570.2 32662.5 32746.8 32999.7 37102.3 37439.3 37503.4 37762.6 38370.4 32036.7 32293.2 32352.8 32559.7 33052.0 25010.4 25677.0 25689.5 25990.4 26285.7 11:58:58 INFO - PROCESS | 15534 | 11:58:58 INFO - PROCESS | 15534 | [#2] jquery.html Cycles:180 Average:37980.50 Median:12567.86 stddev:111110.20 (884.1%) stddev-sans-first:111386.20 11:58:58 INFO - PROCESS | 15534 | Values: 471.7 476.9 482.8 486.4 497.1 19953.8 20031.8 21617.7 21866.1 21898.2 22589.4 22646.0 22855.1 23139.9 23203.1 6562.8 6659.2 6675.3 6731.5 6826.9 6637.8 6747.2 6752.8 6753.9 6756.6 6541.1 6801.8 6832.3 6845.0 6853.1 6528.5 6684.8 6696.1 6748.8 6792.4 6536.6 6546.4 6547.1 6549.1 6554.1 19127.3 19426.5 25715.0 25945.2 25946.4 3139.7 3147.5 3233.3 3462.5 3558.9 15637.3 15850.1 16006.0 16967.9 17284.0 2228.9 2280.6 2330.1 2375.9 2556.4 117894.4 119946.4 122753.0 123064.4 123196.4 18253.2 18412.0 18586.4 18607.0 18683.7 32677.5 32917.8 33064.3 33092.5 33137.7 10802.7 10844.6 11037.7 11040.6 11314.0 9352.2 9369.7 9509.5 9636.7 9637.4 9017.0 9311.0 9434.0 9446.6 9674.6 3164.9 3846.0 3863.8 3878.8 4054.4 960.8 961.2 980.7 981.2 983.2 20801.3 21048.6 21139.3 21192.6 21236.6 982.2 991.5 995.1 996.8 999.1 11061.7 11654.8 11724.4 11777.5 11944.9 652046.0 661994.0 680666.0 686215.0 688799.0 108889.5 109195.0 111514.4 111824.9 112353.0 1222.1 1226.3 1261.9 1269.3 1270.6 17691.7 17890.5 18125.6 18319.9 18435.2 48123.5 48684.0 48713.3 48758.8 49344.3 65206.7 65570.3 66046.0 67274.3 68435.0 11611.4 11816.3 11821.4 11922.9 12183.3 12846.7 13347.3 13415.7 13566.3 13682.7 10550.6 10615.9 11302.2 11334.8 12026.9 11400.6 11611.4 11612.7 11618.8 12149.0 11595.2 11646.1 12639.2 12840.8 13233.7 11785.4 11855.3 11857.0 11887.7 12070.9 10896.5 10953.2 10986.3 11436.8 12468.4 11:58:58 INFO - PROCESS | 15534 | 11:58:58 INFO - PROCESS | 15534 | [#3] mootools.html Cycles:180 Average:7263.63 Median:2465.56 stddev:4510.06 (182.9%) stddev-sans-first:4502.57 11:58:58 INFO - PROCESS | 15534 | Values: 1591.0 1593.5 1621.0 1621.1 1633.9 10992.0 11739.4 11781.5 11788.0 11792.9 11092.2 11378.2 11397.9 11408.3 11482.1 2551.1 2585.7 2595.4 2595.9 2600.7 2431.7 2497.9 2525.9 2529.1 2533.3 2450.5 2506.8 2519.0 2534.9 2535.4 2415.9 2475.1 2482.6 2483.2 2497.1 2430.5 2440.0 2455.0 2456.0 2456.0 11402.7 11546.2 11548.7 11561.2 11562.0 5948.2 6071.2 6079.5 6088.0 6107.3 3477.6 3615.4 3620.4 3671.1 3696.3 1423.6 2262.2 2302.7 2312.8 2335.0 15123.7 15537.5 17050.7 17888.2 17952.3 4809.2 4906.1 4974.4 4987.9 5018.8 13207.3 13230.3 13233.9 13239.1 13257.2 6093.1 6124.4 6127.5 6127.6 6129.7 5474.8 5619.0 5648.2 5672.1 5709.1 3982.9 4061.6 4078.7 4121.5 4144.2 1060.7 1072.3 1073.3 1087.6 1088.5 1107.8 1122.4 1133.6 1151.0 1175.8 7307.1 7450.4 7511.2 7522.0 7615.6 1053.2 1060.8 1065.5 1073.8 1111.6 4819.3 4826.5 4919.9 4942.1 5099.4 14319.4 15381.2 15439.5 15440.4 15445.9 13795.7 14239.3 14258.6 14294.9 15153.5 8941.4 10760.1 10941.7 10965.7 11031.1 6288.5 7299.2 7514.9 7734.3 7777.7 12443.4 12679.6 12746.3 12791.3 12944.8 12699.0 12795.3 13070.5 13157.4 13221.6 9925.2 10129.5 10164.2 10194.4 10304.1 11073.6 11082.5 11092.6 11191.7 11259.2 9255.5 9293.8 9340.7 9516.9 9521.4 9078.3 9149.6 9215.9 9347.0 9370.8 8472.4 8486.8 8496.3 8547.1 8551.8 6623.4 6680.7 6706.8 6720.5 6769.9 6286.2 6306.7 6314.2 6640.4 6658.5 11:58:58 INFO - PROCESS | 15534 | 11:58:58 INFO - PROCESS | 15534 | [#4] prototype.html Cycles:180 Average:4911.02 Median:5369.83 stddev:1251.17 (23.3%) stddev-sans-first:1208.48 11:58:58 INFO - PROCESS | 15534 | Values: 422.6 440.4 448.7 451.5 454.1 5284.4 5322.5 5340.0 5346.9 5368.7 5195.9 5205.7 5219.2 5224.6 5230.7 4324.3 4466.7 4583.4 4587.7 4617.7 4548.7 4564.6 4595.4 4605.4 4618.3 4589.8 4609.9 4620.9 4623.2 4629.9 4439.1 4553.7 4579.6 4598.0 4625.7 4374.7 4380.0 4392.4 4413.2 4421.0 5090.7 5112.5 5159.2 5204.6 5254.5 5116.9 5274.2 5292.7 5310.3 5332.5 5518.5 5543.9 5589.7 5612.7 5621.0 4937.5 4939.6 4945.9 4964.6 5001.2 5467.6 5583.5 5655.9 5727.1 5810.3 4995.0 5036.3 5055.9 5093.3 5093.6 5134.3 5162.0 5162.7 5174.8 5200.9 5209.2 5310.9 5350.9 5351.2 5381.2 5600.2 5602.9 5606.7 5618.5 5621.1 1806.2 1814.3 1826.4 1827.2 1837.4 1413.3 1440.6 1478.7 1482.5 1491.5 5371.0 5385.4 5393.2 5395.7 5438.2 5639.3 5656.7 5673.3 5676.5 5686.7 5129.8 5387.9 5412.6 5414.7 5448.6 2974.7 2986.0 2991.9 3004.7 3008.8 5869.3 5917.0 5917.8 5930.3 5948.9 5498.7 5740.3 5747.7 5751.7 5790.9 5846.2 5850.6 5856.4 5868.0 5871.6 5395.8 5563.6 5658.8 5694.9 5697.0 5390.4 5623.9 5630.9 5652.2 5677.3 5752.6 5786.5 5917.3 5931.4 5946.0 4784.1 4797.4 4797.8 4840.2 4856.2 5473.3 5616.5 5658.9 5665.8 5841.3 5481.4 5543.5 5549.7 5559.9 5560.8 5496.2 5511.0 5542.7 5544.2 5618.9 5316.0 5515.2 5524.9 5531.5 5531.6 5521.8 5568.4 5618.4 5631.6 5640.1 5376.8 5468.5 5526.6 5534.4 5572.9 11:58:58 INFO - PROCESS | 15534 | 11:58:58 INFO - PROCESS | 15534 | [#5] yui.html Cycles:180 Average:18618.12 Median:42274.97 stddev:22810.63 (54.0%) stddev-sans-first:22835.35 11:58:58 INFO - PROCESS | 15534 | Values: 794.5 813.4 881.7 937.1 945.6 16223.9 16506.8 17071.1 17321.6 18778.6 16680.1 16827.2 16959.2 16993.6 17293.7 886.2 911.1 913.9 914.1 914.2 869.8 885.4 888.6 889.8 890.9 934.2 938.2 938.6 938.8 939.5 932.5 935.7 937.6 937.6 937.7 685.6 687.7 688.8 689.1 689.4 17572.0 17587.0 17693.0 19331.7 19609.3 15989.4 16292.0 16597.8 16731.4 16864.7 13221.9 13380.1 13418.1 13508.3 13574.3 3855.7 3867.7 3899.3 3915.4 3996.6 70431.6 92424.5 92725.0 93148.0 94091.5 12356.3 12534.5 12544.1 12639.7 12656.7 24681.4 24764.4 24835.3 24867.8 25070.2 9020.8 9039.0 9040.7 9042.8 9068.6 5053.0 5058.5 5105.8 5127.6 5195.2 3692.9 3747.4 3774.8 3778.4 3783.5 585.0 586.9 590.3 597.1 601.4 640.5 641.5 646.9 653.5 656.4 11194.9 11389.8 11682.9 11846.8 12154.3 761.3 769.3 770.2 773.5 779.0 5221.2 5487.6 5539.9 5587.6 5600.4 76528.6 79494.0 79890.0 81685.6 86619.6 66060.0 66126.0 67050.0 67271.7 67970.0 51091.5 61720.0 62282.0 62454.0 62458.0 11756.4 12016.9 12062.8 12237.9 12354.9 42126.8 42423.2 43000.6 43296.8 43540.8 50270.5 50411.0 50545.5 50552.5 52346.5 13823.0 13957.8 14037.1 14193.6 14330.0 16491.8 16819.6 17562.5 17749.9 17833.7 14986.8 15159.4 15414.9 16237.5 16274.8 15582.5 15733.8 16197.1 16445.1 16453.3 13744.6 13886.4 13959.4 14037.0 14246.9 11680.3 12053.6 12186.1 12286.4 12382.4 12821.7 12834.6 13030.9 13174.4 13241.5 11:58:58 INFO - PROCESS | 15534 | -------- Summary: end -------- 11:58:58 INFO - PROCESS | 15534 | 11:58:59 INFO - TEST-INFO | 15534: exit 0 11:58:59 INFO - TEST-OK | dromaeo_css | took 1115638ms 11:58:59 INFO - TEST-START | kraken 11:58:59 INFO - operating with platform_type : linux_ 11:58:59 INFO - Initialising browser for kraken test... 11:58:59 INFO - TEST-INFO | started process 15842 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWcTUOo/profile http://localhost:45226/getInfo.html) 11:59:03 INFO - PROCESS | 15842 | __metrics Screen width/height:1600/1200 11:59:03 INFO - PROCESS | 15842 | colorDepth:24 11:59:03 INFO - PROCESS | 15842 | Browser inner width/height: 1024/697 11:59:03 INFO - PROCESS | 15842 | __metrics 11:59:04 INFO - PROCESS | 15842 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:59:04 INFO - PROCESS | 15842 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:59:04 INFO - PROCESS | 15842 | console.error: PushService: 11:59:04 INFO - PROCESS | 15842 | stateChangeProcessEnqueue: Error transitioning state 11:59:04 INFO - PROCESS | 15842 | UnknownError 11:59:05 INFO - TEST-INFO | 15842: exit 0 11:59:05 INFO - Browser initialized. 11:59:05 INFO - Running cycle 1/1 for kraken test... 11:59:05 INFO - TEST-INFO | started process 15937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWcTUOo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:59:17 INFO - PROCESS | 15937 | RSS: Main: 141582336 11:59:17 INFO - PROCESS | 15937 | 11:59:19 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45226/tests/kraken/driver.html?testName=audio-beat-detection) 11:59:20 INFO - PROCESS | 15937 | RSS: Main: 184070144 11:59:20 INFO - PROCESS | 15937 | 11:59:22 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45226/tests/kraken/driver.html?testName=audio-dft) 11:59:23 INFO - PROCESS | 15937 | RSS: Main: 174333952 11:59:23 INFO - PROCESS | 15937 | 11:59:26 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45226/tests/kraken/driver.html?testName=audio-fft) 11:59:26 INFO - PROCESS | 15937 | RSS: Main: 265637888 11:59:26 INFO - PROCESS | 15937 | 11:59:28 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45226/tests/kraken/driver.html?testName=audio-oscillator) 11:59:29 INFO - PROCESS | 15937 | RSS: Main: 172298240 11:59:29 INFO - PROCESS | 15937 | 11:59:31 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:59:32 INFO - PROCESS | 15937 | RSS: Main: 174374912 11:59:32 INFO - PROCESS | 15937 | 11:59:35 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45226/tests/kraken/driver.html?testName=imaging-darkroom) 11:59:35 INFO - PROCESS | 15937 | RSS: Main: 212176896 11:59:35 INFO - PROCESS | 15937 | 11:59:39 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45226/tests/kraken/driver.html?testName=imaging-desaturate) 11:59:39 INFO - PROCESS | 15937 | RSS: Main: 213049344 11:59:39 INFO - PROCESS | 15937 | 11:59:42 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45226/tests/kraken/driver.html?testName=json-parse-financial) 11:59:43 INFO - PROCESS | 15937 | RSS: Main: 211968000 11:59:43 INFO - PROCESS | 15937 | 11:59:44 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:59:45 INFO - PROCESS | 15937 | RSS: Main: 173981696 11:59:45 INFO - PROCESS | 15937 | 11:59:46 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45226/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:59:47 INFO - PROCESS | 15937 | RSS: Main: 180047872 11:59:47 INFO - PROCESS | 15937 | 11:59:49 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:59:50 INFO - PROCESS | 15937 | RSS: Main: 190550016 11:59:50 INFO - PROCESS | 15937 | 11:59:52 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:59:53 INFO - PROCESS | 15937 | RSS: Main: 199753728 11:59:53 INFO - PROCESS | 15937 | 11:59:56 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:59:56 INFO - PROCESS | 15937 | RSS: Main: 195358720 11:59:56 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | Cycle 1(1): loaded http://localhost:45226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45226/tests/kraken/driver.html?testName=ai-astar) 11:59:58 INFO - PROCESS | 15937 | RSS: Main: 246976512 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | __start_tp_report 11:59:58 INFO - PROCESS | 15937 | _x_x_mozilla_page_load 11:59:58 INFO - PROCESS | 15937 | _x_x_mozilla_page_load_details 11:59:58 INFO - PROCESS | 15937 | |i|pagename|runs| 11:59:58 INFO - PROCESS | 15937 | |0;ai-astar;103;112;113;111;112;112;110;111;113;110 11:59:58 INFO - PROCESS | 15937 | |1;audio-beat-detection;141;116;115;144;117;116;116;115;130;117 11:59:58 INFO - PROCESS | 15937 | |2;audio-dft;164;163;163;162;161;161;161;160;160;161 11:59:58 INFO - PROCESS | 15937 | |3;audio-fft;74;75;76;111;76;76;76;75;95;76 11:59:58 INFO - PROCESS | 15937 | |4;audio-oscillator;82;83;103;82;83;82;99;82;82;94 11:59:58 INFO - PROCESS | 15937 | |5;imaging-gaussian-blur;113;113;112;113;113;113;113;112;112;112 11:59:58 INFO - PROCESS | 15937 | |6;imaging-darkroom;146;146;146;146;147;146;147;147;146;146 11:59:58 INFO - PROCESS | 15937 | |7;imaging-desaturate;93;94;94;94;94;94;94;93;94;94 11:59:58 INFO - PROCESS | 15937 | |8;json-parse-financial;55;56;55;54;55;56;56;55;56;57 11:59:58 INFO - PROCESS | 15937 | |9;json-stringify-tinderbox;53;53;53;57;53;53;53;54;56;53 11:59:58 INFO - PROCESS | 15937 | |10;stanford-crypto-aes;76;75;77;79;76;79;76;81;82;79 11:59:58 INFO - PROCESS | 15937 | |11;stanford-crypto-ccm;121;120;121;121;120;121;121;119;172;127 11:59:58 INFO - PROCESS | 15937 | |12;stanford-crypto-pbkdf2;163;161;160;157;157;162;161;159;155;158 11:59:58 INFO - PROCESS | 15937 | |13;stanford-crypto-sha256-iterative;61;59;61;59;60;61;61;64;58;59 11:59:58 INFO - PROCESS | 15937 | __end_tp_report 11:59:58 INFO - PROCESS | 15937 | __start_cc_report 11:59:58 INFO - PROCESS | 15937 | _x_x_mozilla_cycle_collect,1668 11:59:58 INFO - PROCESS | 15937 | __end_cc_report 11:59:58 INFO - PROCESS | 15937 | __startTimestamp1495738798509__endTimestamp 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | ------- Summary: start ------- 11:59:58 INFO - PROCESS | 15937 | Number of tests: 14 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:2.91 (2.6%) stddev-sans-first:1.13 11:59:58 INFO - PROCESS | 15937 | Values: 103.0 112.0 113.0 111.0 112.0 112.0 110.0 111.0 113.0 110.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:117.00 stddev:11.35 (9.7%) stddev-sans-first:9.92 11:59:58 INFO - PROCESS | 15937 | Values: 141.0 116.0 115.0 144.0 117.0 116.0 116.0 115.0 130.0 117.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:1.35 (0.8%) stddev-sans-first:1.12 11:59:58 INFO - PROCESS | 15937 | Values: 164.0 163.0 163.0 162.0 161.0 161.0 161.0 160.0 160.0 161.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:12.21 (16.1%) stddev-sans-first:12.69 11:59:58 INFO - PROCESS | 15937 | Values: 74.0 75.0 76.0 111.0 76.0 76.0 76.0 75.0 95.0 76.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#4] audio-oscillator Cycles:10 Average:87.20 Median:82.50 stddev:8.20 (9.9%) stddev-sans-first:8.48 11:59:58 INFO - PROCESS | 15937 | Values: 82.0 83.0 103.0 82.0 83.0 82.0 99.0 82.0 82.0 94.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#5] imaging-gaussian-blur Cycles:10 Average:112.60 Median:113.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 11:59:58 INFO - PROCESS | 15937 | Values: 113.0 113.0 112.0 113.0 113.0 113.0 113.0 112.0 112.0 112.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 11:59:58 INFO - PROCESS | 15937 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 147.0 147.0 146.0 146.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 11:59:58 INFO - PROCESS | 15937 | Values: 93.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.00 stddev:0.85 (1.5%) stddev-sans-first:0.88 11:59:58 INFO - PROCESS | 15937 | Values: 55.0 56.0 55.0 54.0 55.0 56.0 56.0 55.0 56.0 57.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#9] json-stringify-tinderbox Cycles:10 Average:53.80 Median:53.00 stddev:1.48 (2.8%) stddev-sans-first:1.54 11:59:58 INFO - PROCESS | 15937 | Values: 53.0 53.0 53.0 57.0 53.0 53.0 53.0 54.0 56.0 53.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:79.00 stddev:2.36 (3.0%) stddev-sans-first:2.39 11:59:58 INFO - PROCESS | 15937 | Values: 76.0 75.0 77.0 79.0 76.0 79.0 76.0 81.0 82.0 79.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:121.00 stddev:16.20 (13.4%) stddev-sans-first:17.07 11:59:58 INFO - PROCESS | 15937 | Values: 121.0 120.0 121.0 121.0 120.0 121.0 121.0 119.0 172.0 127.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.30 Median:160.50 stddev:2.54 (1.6%) stddev-sans-first:2.32 11:59:58 INFO - PROCESS | 15937 | Values: 163.0 161.0 160.0 157.0 157.0 162.0 161.0 159.0 155.0 158.0 11:59:58 INFO - PROCESS | 15937 | 11:59:58 INFO - PROCESS | 15937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.30 Median:61.00 stddev:1.70 (2.8%) stddev-sans-first:1.79 11:59:58 INFO - PROCESS | 15937 | Values: 61.0 59.0 61.0 59.0 60.0 61.0 61.0 64.0 58.0 59.0 11:59:58 INFO - PROCESS | 15937 | -------- Summary: end -------- 11:59:58 INFO - PROCESS | 15937 | 11:59:59 INFO - TEST-INFO | 15937: exit 0 11:59:59 INFO - TEST-OK | kraken | took 60030ms 11:59:59 INFO - SUITE-END | took 1175s 11:59:59 INFO - Completed test suite (00:19:35) 11:59:59 INFO - javascript benchmark 11:59:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7962.07034892244, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3384.3569721752865, 3451.775634583752, 3463.2554000889804, 3478.217401084755, 3503.5795704515444, 5798.217240672508, 5823.388544202861, 5876.382979787119, 5882.911756323648, 5886.087656413258, 5824.650520968745, 5870.61840980336, 5878.23582934219, 5892.528597712183, 5894.675792831404, 5339.22581225722, 5342.572594192465, 5355.651882627617, 5385.649932754378, 5456.48163424476, 5165.328507294065, 5427.375851777037, 5438.592105592076, 5446.673199608016, 5454.072807622694, 5196.532312091911, 5272.789088436458, 5328.333958255218, 5329.493698098694, 5341.172118321647, 5388.838334849961, 5423.810166644149, 5427.891442171134, 5428.891422171554, 5432.70120143392, 5318.574514038906, 5329.546988505982, 5349.0, 5370.087085195526, 5392.299001129828, 5604.8038318659055, 5649.915251271234, 5749.0514065179195, 5805.5065319447895, 5812.970935145339, 6295.150154729145, 6306.274778400497, 6307.810765677037, 6315.557910946236, 6318.43134117927, 6363.204599425072, 6363.7772677956045, 6364.395382438661, 6371.203599550056, 6371.904421433682, 5651.2935883014625, 5691.829245122694, 5787.8553036174435, 5793.304803423616, 5911.083782013795, 6314.336994615591, 6364.299927008025, 6367.681615919186, 6369.617822930546, 6432.742690292336, 6127.877442451126, 6177.938220617826, 6194.5044396448175, 6194.628322300677, 6195.31851496335, 6062.393760623991, 6095.786647467317, 6143.511877689543, 6260.154879091356, 6262.906056409158, 6264.436200741955, 6271.372862713783, 6281.3404592518045, 6285.057241413824, 6291.6854157291955, 5956.7319470623925, 5997.910031349533, 5998.730057147439, 6291.339409362043, 6299.0, 4650.209464391061, 4695.929561056587, 4705.082508910729, 4709.1994360958715, 4732.100900828831, 4184.602462766033, 4196.475440569929, 4297.742135471882, 4307.483102027777, 4335.7615331157085, 5879.265091863517, 5892.175095486636, 5893.970530147321, 5899.439553242435, 5935.881282374415, 6277.24673039229, 6311.873762524724, 6352.983522636356, 6353.904691429632, 6358.427741503287, 5816.41835816415, 5998.310194327621, 6010.459058684739, 6018.8194354169445, 6053.424924632153, 5295.391030031596, 5314.707691077027, 5344.946550534445, 5391.0, 5401.270828438111, 6562.737490500326, 6586.703598338182, 6600.174978127734, 6603.405693487513, 6604.768833090915, 6274.247090349188, 6312.5581209314905, 6320.051992201023, 6326.778562750282, 6331.145295385249, 6312.873742525032, 6347.936520634919, 6427.646479443675, 6479.254885688206, 6580.309067547839, 6209.130721699056, 6212.099245609271, 6212.347703491068, 6214.40963108504, 6220.129181914445, 6270.529710271839, 6280.874382512233, 6281.089242059785, 6282.994720844643, 6309.148264984168, 5885.205497257815, 5910.231669882888, 6063.514918806397, 6080.087986802015, 6105.45050945417, 6120.693965301806, 6126.700892189639, 6135.110179431545, 6144.631322120687, 6144.692765361803, 6124.652576433177, 6131.141640170291, 6135.11040899076, 6155.076738489083, 6157.384261573807, 5894.115882617471, 5940.049592065248, 5978.790742324171, 6108.938910611015, 6128.938710612837, 6061.060535616986, 6061.121137435135, 6066.575339726353, 6068.484178844671, 6090.086487026804], "value": 5774.168398670399, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.1790066727934, 841.8274253777975, 854.5769843927255, 905.5064989580678, 906.9773255668612, 33067.669323306756, 44941.325880111784, 45988.770056149755, 46054.309185362246, 46082.539174608246, 43027.13945721084, 43212.35181472272, 43315.78342108293, 46784.53215467844, 47020.29469557959, 7617.695292188306, 7805.804854878616, 9158.679446219383, 9279.907200928006, 9296.163345298923, 10557.260991730582, 10870.891291087068, 11213.831792523119, 11214.887851121506, 11218.27081239721, 10977.83533246998, 10982.286151400174, 11033.172512061587, 11043.171762117832, 11045.668629941074, 10901.236913416064, 10919.39943303118, 10931.617393391272, 10937.234393592451, 10942.343459392383, 10687.839682404692, 10756.784864302748, 10759.0, 10764.407957562331, 10765.461726913702, 44098.77950610226, 44164.11671766579, 44179.77910110429, 44301.0, 44324.77837610824, 4310.978445107786, 4378.540253273392, 4386.363977223316, 4472.9776351118035, 4506.549345065468, 31221.53167702486, 31549.684503154906, 32031.839840800647, 32238.838805806055, 33186.50220246698, 4346.869593912188, 4367.148406060819, 4405.625521830616, 4419.513853476115, 4464.709793863389, 241238.0, 242457.0, 243183.0, 244412.0, 244777.0, 55896.0, 56038.439615603325, 56303.34028288148, 56333.0, 56352.15471767927, 57963.71018144882, 58101.418985810444, 58230.417695823344, 58256.834863302494, 58338.70830645905, 17342.653146937242, 17407.65184696299, 17410.477685669197, 17440.912795435943, 17442.65114697699, 13325.333733313295, 13561.728765424636, 14880.479183228535, 15458.845411545966, 15464.14947177916, 14047.578572642837, 14119.43522259098, 14134.36395362211, 14300.785488217685, 14337.64155896111, 5581.553475721932, 5588.91616625751, 5617.859553511195, 5640.210370548127, 5643.633163844337, 1006.4263369879098, 1006.8439391894268, 1011.145581983223, 1014.1785154025263, 1015.7206768138673, 29097.85451072731, 29271.268218294714, 29372.559411608843, 29671.406571868443, 29803.701962980525, 1078.9730256743644, 1086.9565217391216, 1092.1317552545745, 1093.578972095749, 1101.531848964187, 14836.554903352917, 15712.371505139896, 15732.60668483297, 15802.36790528366, 15823.0, 327314.0, 327964.0, 330183.0, 330228.0, 330806.0, 59801.700991495636, 59949.10076348771, 59950.0, 59960.0, 60050.699746500984, 1469.8897582681127, 1499.7225513280077, 1521.634807646157, 1526.778617100514, 1534.7851300817895, 28593.857030714713, 30054.84972575123, 30153.24616884508, 30296.697033029388, 30733.538996914616, 38467.2306553873, 38524.80737596294, 38575.6142438572, 38621.42067868928, 38654.42018369783, 303727.0, 304286.0, 305289.0, 305327.0, 306814.0, 25091.24726258215, 26121.0, 27427.177184684493, 31555.526667100472, 31562.68437315689, 32316.0, 33335.0, 33504.66495335113, 39516.60483395129, 39770.403443947784, 30296.242593935327, 30969.380612388075, 31227.531587026668, 32101.518477222395, 33563.32873342471, 32494.187645309055, 32570.185745356557, 32662.51006234956, 32746.836265818518, 32999.67000329966, 37102.25795484129, 37439.251214975004, 37503.43744843775, 37762.622373775914, 38370.42444363393, 32036.6796332043, 32293.192670183435, 32352.83823580867, 32559.674403255663, 33052.0, 25010.3747406309, 25677.0, 25689.486210276064, 25990.35024124412, 26285.737142629096], "value": 19196.15799612074, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.73582793635586, 476.88077980504875, 482.7706839251356, 486.3871521882427, 497.1151350595939, 19953.800461995375, 20031.799682003177, 21617.67573486399, 21866.125354985783, 21898.23356182534, 22589.435264118405, 22646.0, 22855.085796568157, 23139.884300578473, 23203.071877124938, 6562.770303039395, 6659.234188068385, 6675.332466753334, 6731.495137864656, 6826.897596536056, 6637.800865974004, 6747.224069232027, 6752.763653272136, 6753.8986915196065, 6756.594604323732, 6541.116949211867, 6801.829954251134, 6832.2826103259185, 6845.0, 6853.143357080365, 6528.543001989862, 6684.832879178035, 6696.096027036337, 6748.831279218034, 6792.422644075259, 6536.640484773336, 6546.378094081102, 6547.050677651761, 6549.083128362033, 6554.082428460019, 19127.33054343091, 19426.514337141543, 25715.0, 25945.22164335073, 25946.351341216432, 3139.748820094387, 3147.512135618983, 3233.2563510392743, 3462.515247865301, 3558.946615800765, 15637.296321665552, 15850.128242946634, 16006.0, 16967.915160424243, 17284.0, 2228.9219877304376, 2280.6350983842503, 2330.102910379499, 2375.881205939696, 2556.437583731576, 117894.4105279468, 119946.4002679981, 122753.0, 123064.38467807604, 123196.38401808024, 18253.17860696272, 18412.0, 18586.44240672782, 18607.0, 18683.71974420385, 32677.50983735246, 32917.835410823274, 33064.3387132256, 33092.503612445835, 33137.66862331346, 10802.675919722264, 10844.566217351376, 11037.668869933916, 11040.613578524713, 11314.0, 9352.205062569758, 9369.70911549979, 9509.524523773784, 9636.662716804878, 9637.4217546946, 9016.979311930576, 9310.953445232866, 9434.0, 9446.574904129331, 9674.56464459101, 3164.905052848418, 3846.0, 3863.787491687984, 3878.825452854628, 4054.412110243998, 960.8174446855076, 961.245422343457, 980.6910823090847, 981.1611072532882, 983.1643103362228, 20801.271955481487, 21048.579028419652, 21139.26012589552, 21192.576148476943, 21236.575268494544, 982.2240430060315, 991.5389343955095, 995.144176008632, 996.7508122969258, 999.0758548342753, 11061.723456913642, 11654.76690466186, 11724.413779311171, 11777.470013849397, 11944.940275298568, 652046.0, 661994.0, 680666.0, 686215.0, 688799.0, 108889.45555272173, 109195.0, 111514.44242778735, 111824.88175118144, 112353.0, 1222.113967373647, 1226.2519862883635, 1261.9495220191272, 1269.346286662351, 1270.5934101087748, 17691.73462398091, 17890.463286101436, 18125.637487250446, 18319.908400457913, 18435.170417330984, 48123.5187648119, 48684.0, 48713.26930095981, 48758.75620621874, 49344.25983610321, 65206.67396662986, 65570.34429655834, 66046.0, 67274.32725672876, 68435.0, 11611.361375124448, 11816.291022538675, 11821.40892955366, 11922.880771192176, 12183.329916854304, 12846.67883302925, 13347.332633368098, 13415.731685366043, 13566.321683915961, 13682.657933551742, 10550.630727924487, 10615.946920265349, 11302.208845380745, 11334.829977550507, 12026.879731202576, 11400.600978965696, 11611.419429028347, 11612.6516204514, 11618.76762464763, 12149.0, 11595.246308989888, 11646.126540509325, 12639.241645501299, 12840.807387889003, 13233.735325293248, 11785.410729463663, 11855.288682679067, 11857.0, 11887.702807429883, 12070.939645301718, 10896.50965706561, 10953.233273670767, 10986.285891417032, 11436.82844757346, 12468.438920248429], "value": 11404.68907352658, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1590.9920450397747, 1593.4821183115491, 1620.9594760130994, 1621.0678859655698, 1633.8529532342095, 10992.0, 11739.354335511543, 11781.528738850435, 11787.999999999989, 11792.941035294833, 11092.223544351898, 11378.2035257532, 11397.886021139786, 11408.315501069941, 11482.081433485322, 2551.1453663022908, 2585.741425857418, 2595.416031392931, 2595.9351016224555, 2600.7399260073935, 2431.671724317221, 2497.9375515612073, 2525.9242222733255, 2529.064246228898, 2533.3160046787357, 2450.4608986023045, 2506.8119891008155, 2519.0, 2534.8986040558448, 2535.378832186117, 2415.903363865443, 2475.084218839023, 2482.6027835546406, 2483.1557270528015, 2497.126005897939, 2430.501747141832, 2439.9634005489934, 2455.0302630461047, 2456.0421435640114, 2456.0421435640114, 11402.71493212668, 11546.249493782962, 11548.711282218012, 11561.248518846332, 11562.0, 5948.167256584082, 6071.1803906472505, 6079.544034197453, 6088.025915853486, 6107.297660769024, 3477.643718949612, 3615.4215325547784, 3620.3664358737115, 3671.0822294426393, 3696.2977034363653, 1423.6298562373754, 2262.196920093376, 2302.7466978632337, 2312.8034117100065, 2335.0, 15123.697526049418, 15537.533873983686, 17050.65898682032, 17888.195031223495, 17952.281908723635, 4809.182438985381, 4906.1414252505665, 4974.403071631427, 4987.850364489072, 5018.774155163026, 13207.339633018311, 13230.272335021473, 13233.933830330787, 13239.139455935334, 13257.204567725967, 6093.086037094471, 6124.387561243929, 6127.479164270998, 6127.571070025145, 6129.662868542273, 5474.808381706621, 5618.971905140448, 5648.152777083388, 5672.064109421982, 5709.143628455765, 3982.9402558961638, 4061.634452899194, 4078.6737061035046, 4121.505419349697, 4144.150449157915, 1060.7136073260176, 1072.3030030480095, 1073.2701762801214, 1087.6465148826662, 1088.5101704232968, 1107.7562936154034, 1122.4107343644653, 1133.5975728616388, 1150.9654710358702, 1175.8118701007797, 7307.1231452226075, 7450.441216908754, 7511.173770885198, 7521.962390188014, 7615.581143037186, 1053.2153545608417, 1060.8355704865758, 1065.541817018674, 1073.8013467508536, 1111.6331610568493, 4819.277108433763, 4826.493218212107, 4919.893024069627, 4942.135126352874, 5099.413567439718, 14319.35562899672, 15381.154036528098, 15439.459618913284, 15440.382384704712, 15445.922770386302, 13795.655108622364, 14239.288035598385, 14258.643533911736, 14294.928525357514, 15153.469628563167, 8941.418807777343, 10760.139188864872, 10941.726456838484, 10965.671029869116, 11031.062359699516, 6288.516036165597, 7299.160596531467, 7514.887276690744, 7734.342580880463, 7777.650005749868, 12443.37783110859, 12679.556215532411, 12746.298953557643, 12791.296478693761, 12944.8058279124, 12699.0, 12795.296258705488, 13070.477180912658, 13157.407916643582, 13221.603351899459, 9925.205983521446, 10129.544170512198, 10164.237682174014, 10194.388336699822, 10304.124149447382, 11073.5570577176, 11082.501287441923, 11092.556297748322, 11191.720206994565, 11259.155563332619, 9255.490947997656, 9293.814123717353, 9340.673076442292, 9516.85724714116, 9521.381110227812, 9078.31912606544, 9149.63401463934, 9215.86176207344, 9347.0, 9370.812583748151, 8472.406931514735, 8486.830263394573, 8496.32029437656, 8547.145285471403, 8551.786205344915, 6623.4038936495945, 6680.6993685283305, 6706.765263215764, 6720.529562930548, 6769.864602707819, 6286.245650521967, 6306.747730090745, 6314.210723659543, 6640.435562977201, 6658.467322614276], "value": 5595.216916183185, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.57953336430245, 440.41424904876516, 448.68816172759944, 451.45599552539215, 454.10767841192074, 5284.357821089456, 5322.467753224676, 5339.9708110917845, 5346.866328341794, 5368.677879327243, 5195.922061169085, 5205.65745414563, 5219.217117432387, 5224.608154388427, 5230.6600070995355, 4324.264874971254, 4466.731996080245, 4583.381243532131, 4587.678862479628, 4617.699849509788, 4548.74909399916, 4564.566366195223, 4595.448546174463, 4605.424321959755, 4618.307253911907, 4589.816407343719, 4609.884752881188, 4620.861374158781, 4623.237165867645, 4629.93055104172, 4439.1121775645, 4553.726776393427, 4579.610733087658, 4597.977010114962, 4625.676202665798, 4374.715643483195, 4379.977815307428, 4392.407025051609, 4413.249747542925, 4421.0, 5090.720010399426, 5112.488751124895, 5159.2003239497935, 5204.635675502716, 5254.500822421864, 5116.923246151311, 5274.182501712241, 5292.735363231823, 5310.291751206873, 5332.546733527654, 5518.503334699896, 5543.944560554423, 5589.7205139743255, 5612.69130197839, 5620.971895140539, 4937.530934561211, 4939.629527785431, 4945.887175385546, 4964.553190212862, 5001.174806157016, 5467.589930755209, 5583.469570390806, 5655.8586035349035, 5727.140928860663, 5810.302763668345, 4994.950050499521, 5036.269740887551, 5055.9494405055475, 5093.337866077386, 5093.643444958891, 5134.332536770271, 5162.0, 5162.6902385856965, 5174.793008279702, 5200.947990520122, 5209.218617207449, 5310.893782124336, 5350.946490535122, 5351.224072509464, 5381.152475979693, 5600.243967064475, 5602.943970560324, 5606.682429629034, 5618.522425593788, 5621.133222056698, 1806.2323512507132, 1814.2561549764798, 1826.3972888946616, 1827.2234300422267, 1837.4120281509788, 1413.300416293941, 1440.5678296511005, 1478.7042591481531, 1482.495951376537, 1491.5376233367474, 5370.9731451342495, 5385.353757549041, 5393.191021346829, 5395.6798576611645, 5438.15708565173, 5639.3232812062, 5656.6888821115235, 5673.3475650299915, 5676.545876329884, 5686.658800471985, 5129.820456284012, 5387.946120538744, 5412.648177868426, 5414.702191379433, 5448.564114870801, 2974.6500264791007, 2985.9701402986125, 2991.880324786984, 3004.744596709304, 3008.849557522115, 5869.2956845178, 5916.970415147896, 5917.786853694982, 5930.347661757203, 5948.940510594925, 5498.6630681960005, 5740.282464691913, 5747.666353514951, 5751.654900705971, 5790.855228619234, 5846.239988801429, 5850.5612079094235, 5856.385079566755, 5867.970660146757, 5871.64770113786, 5395.81114660985, 5563.554915606661, 5658.830235092953, 5694.857628559319, 5696.971515142398, 5390.380106287827, 5623.915641265303, 5630.915536267041, 5652.236948012131, 5677.318721753416, 5752.597318187687, 5786.495511167042, 5917.3490915998345, 5931.377205393372, 5946.0, 4784.138855006132, 4797.4482934462985, 4797.760111994457, 4840.249761287007, 4856.223004319292, 5473.31583552056, 5616.466435688603, 5658.85852853682, 5665.773369065191, 5841.283803031611, 5481.397046324981, 5543.52880005204, 5549.724612908688, 5559.944400555943, 5560.805371811967, 5496.203050557567, 5511.035568775368, 5542.72286385671, 5544.223808666709, 5618.915716264178, 5315.973420132875, 5515.200295957144, 5524.917126243029, 5531.502164805187, 5531.585131115263, 5521.751521181637, 5568.359638641608, 5618.410066942912, 5631.605787594829, 5640.080144299673, 5376.8118115865755, 5468.484497131205, 5526.613137080366, 5534.363548191848, 5572.86067848307], "value": 4559.160278823605, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.479615851617, 813.3696385301395, 881.7487016200382, 937.081659973226, 945.6359301668859, 16223.918880405581, 16506.75239871403, 17071.146442677848, 17321.566960825985, 18778.62442751146, 16680.082595457276, 16827.15864206788, 16959.236834342453, 16993.575160620992, 17293.740593891103, 886.2156991062907, 911.0661571888801, 913.8857642794651, 914.0676509959837, 914.2229105260509, 869.8260347930408, 885.4023534114447, 888.6312180445108, 889.7864512517014, 890.9198172164537, 934.2059249637822, 938.1509733690987, 938.5823308627663, 938.7700013496669, 939.5208443693697, 932.4591736792712, 935.7192842147329, 937.6015193542717, 937.6483818568003, 937.7186843946789, 685.6160550091914, 687.7489716253582, 688.7933619914056, 689.0628744906923, 689.4381079420257, 17572.0, 17587.03271320077, 17693.0, 19331.710024349646, 19609.31367402134, 15989.440369587008, 16292.0, 16597.751033734505, 16731.414400496047, 16864.747028794576, 13221.933890330583, 13380.063395562314, 13418.127821691656, 13508.257045862474, 13574.253416062113, 3855.7108216883844, 3867.7099217558516, 3899.3020937188435, 3915.393114067324, 3996.6403023728003, 70431.64784176047, 92424.53787731085, 92725.0, 93148.0, 94091.52954235254, 12356.25862448256, 12534.498620055096, 12544.121911466109, 12639.747205055848, 12656.683582910317, 24681.382965426008, 24764.38089047788, 24835.25494235176, 24867.751322486907, 25070.247892562886, 9020.774480637905, 9038.954805225932, 9040.728778136667, 9042.773930651654, 9068.637254509746, 5053.0, 5058.519440653132, 5105.846824595268, 5127.589792816566, 5195.220716892496, 3692.8892133236045, 3747.38168202249, 3774.7735135891385, 3778.4332350147697, 3783.5459744830246, 585.0492948957943, 586.9148973398833, 590.2503820148473, 597.0685730260807, 601.4106175947509, 640.5068097564942, 641.5348872067807, 646.9385408386196, 653.4870126950321, 656.3567703650353, 11194.888051119384, 11389.772204555862, 11682.88317116818, 11846.822297665542, 12154.270743755225, 761.2539711083059, 769.3114662377141, 770.2220757035323, 773.4856320546886, 778.9805254868674, 5221.190715439113, 5487.6158668892795, 5539.944600554023, 5587.608867379326, 5600.4399560044485, 76528.61735691286, 79494.0, 79890.0, 81685.59157204175, 86619.56690216508, 66060.0, 66126.0, 67050.0, 67271.66364168246, 67970.0, 51091.48908510942, 61720.0, 62282.0, 62454.0, 62458.0, 11756.353400563052, 12016.939915300367, 12062.819057713965, 12237.938810305892, 12354.876451235732, 42126.7893660542, 42423.15153696847, 43000.56999429966, 43296.78351608222, 43540.78229608832, 50270.49729502804, 50411.0, 50545.494545054076, 50552.49447505478, 52346.476535234164, 13823.01016982844, 13957.790633140714, 14037.087589306662, 14193.574192774233, 14330.0, 16491.752623710894, 16819.579510512336, 17562.473125806246, 17749.911250443663, 17833.73249401286, 14986.775198372252, 15159.393624255348, 15414.92292538575, 16237.51287461378, 16274.755878662067, 15582.454614088452, 15733.842661573237, 16197.109158995898, 16445.09551974605, 16453.259603318118, 13744.587662370144, 13886.444542218198, 13959.371828267549, 14037.0, 14246.857531424554, 11680.299182049104, 12053.57812476559, 12186.086043546593, 12286.447109880255, 12382.442790074649, 12821.743565128458, 12834.614961551168, 13030.869691303345, 13174.407151678006, 13241.536546220836], "value": 7900.063418057584, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1449.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 113.0, 111.0, 112.0, 112.0, 110.0, 111.0, 113.0, 110.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 115.0, 144.0, 117.0, 116.0, 116.0, 115.0, 130.0, 117.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 163.0, 162.0, 161.0, 161.0, 161.0, 160.0, 160.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 111.0, 76.0, 76.0, 76.0, 75.0, 95.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 103.0, 82.0, 83.0, 82.0, 99.0, 82.0, 82.0, 94.0], "value": 87.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 112.0, 112.0, 112.0], "value": 112.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 54.0, 55.0, 56.0, 56.0, 55.0, 56.0, 57.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 57.0, 53.0, 53.0, 53.0, 54.0, 56.0, 53.0], "value": 53.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 79.0, 76.0, 79.0, 76.0, 81.0, 82.0, 79.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 121.0, 121.0, 120.0, 121.0, 121.0, 119.0, 172.0, 127.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 160.0, 157.0, 157.0, 162.0, 161.0, 159.0, 155.0, 158.0], "value": 159.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 61.0, 59.0, 60.0, 61.0, 61.0, 64.0, 58.0, 59.0], "value": 60.3, "unit": "score"}]}]} 11:59:59 INFO - Return code: 0 11:59:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:59:59 INFO - # TBPL SUCCESS # 11:59:59 INFO - Running post-action listener: _resource_record_post_action 11:59:59 INFO - [mozharness: 2017-05-25 18:59:59.813598Z] Finished run-tests step (success) 11:59:59 INFO - Running post-run listener: _resource_record_post_run 11:59:59 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 341057536; Read time: 100; Write time: 197728 11:59:59 INFO - TinderboxPrint: CPU usage
13.1% 11:59:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 100 11:59:59 INFO - TinderboxPrint: I/O write bytes / time
341,057,536 / 197,728 11:59:59 INFO - TinderboxPrint: CPU idle
8,254.1 (86.9%) 11:59:59 INFO - TinderboxPrint: CPU user
1,198.0 (12.6%) 11:59:59 INFO - TinderboxPrint: Swap in / out
0 / 0 11:59:59 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 15515648; Read time: 0; Write time: 4220 11:59:59 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 239669248; Read time: 0; Write time: 143132 12:00:00 INFO - Running post-run listener: _upload_blobber_files 12:00:00 INFO - Blob upload gear active. 12:00:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:00:00 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 12:00:00 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:00:00 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:00:00 INFO - (blobuploader) - INFO - Open directory for files ... 12:00:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:00:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:00:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:01 INFO - (blobuploader) - INFO - Done attempting. 12:00:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:00:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:00:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:02 INFO - (blobuploader) - INFO - Done attempting. 12:00:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:00:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:00:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:08 INFO - (blobuploader) - INFO - Done attempting. 12:00:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:00:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:00:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:09 INFO - (blobuploader) - INFO - Done attempting. 12:00:09 INFO - (blobuploader) - INFO - Iteration through files over. 12:00:09 INFO - Return code: 0 12:00:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:00:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:00:09 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c14e40b83a69dfbcf4530385c46a8fd634afa2f13f6dda6c9a48aafcd6f15fe7e562ccb192ea8f142fce264191a838547c7f04d4d19f64191f9a43e126367467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b2cced082e1e4cb5767f349354e7b36754772e58aca1083faaadace88ee301130f0bc82f31cfcd9ec631b21059e7c9702f16eef60e9cf541caab00a4247a65ec", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/74c3a6d7017021b0429296cb2429c3cccabf9230460492410480c7a36970b56a60e0942398c84b592a6581cb7914bd024ef2df5aec608b9635597771e3e70b28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7ca185347afc3498e796e2ad8c543b9689b854750307b603baa4a627467ffa90e308be845ab6c282048327be14324c29e4a9fcb9bcfd66187c6c5a93388a7dbf"} 12:00:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:00:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:00:09 INFO - Contents: 12:00:09 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c14e40b83a69dfbcf4530385c46a8fd634afa2f13f6dda6c9a48aafcd6f15fe7e562ccb192ea8f142fce264191a838547c7f04d4d19f64191f9a43e126367467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b2cced082e1e4cb5767f349354e7b36754772e58aca1083faaadace88ee301130f0bc82f31cfcd9ec631b21059e7c9702f16eef60e9cf541caab00a4247a65ec", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/74c3a6d7017021b0429296cb2429c3cccabf9230460492410480c7a36970b56a60e0942398c84b592a6581cb7914bd024ef2df5aec608b9635597771e3e70b28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7ca185347afc3498e796e2ad8c543b9689b854750307b603baa4a627467ffa90e308be845ab6c282048327be14324c29e4a9fcb9bcfd66187c6c5a93388a7dbf"} 12:00:09 INFO - Running post-run listener: copy_logs_to_upload_dir 12:00:09 INFO - Copying logs to upload dir... 12:00:09 INFO - mkdir: /builds/slave/test/build/upload/logs 12:00:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.016509 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-25 12:00:09.052715) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 12:00:09.055266) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c14e40b83a69dfbcf4530385c46a8fd634afa2f13f6dda6c9a48aafcd6f15fe7e562ccb192ea8f142fce264191a838547c7f04d4d19f64191f9a43e126367467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b2cced082e1e4cb5767f349354e7b36754772e58aca1083faaadace88ee301130f0bc82f31cfcd9ec631b21059e7c9702f16eef60e9cf541caab00a4247a65ec", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/74c3a6d7017021b0429296cb2429c3cccabf9230460492410480c7a36970b56a60e0942398c84b592a6581cb7914bd024ef2df5aec608b9635597771e3e70b28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7ca185347afc3498e796e2ad8c543b9689b854750307b603baa4a627467ffa90e308be845ab6c282048327be14324c29e4a9fcb9bcfd66187c6c5a93388a7dbf"} build_url:https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/Sm_Ke8X4Qbi-vJppj8SwDQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c14e40b83a69dfbcf4530385c46a8fd634afa2f13f6dda6c9a48aafcd6f15fe7e562ccb192ea8f142fce264191a838547c7f04d4d19f64191f9a43e126367467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b2cced082e1e4cb5767f349354e7b36754772e58aca1083faaadace88ee301130f0bc82f31cfcd9ec631b21059e7c9702f16eef60e9cf541caab00a4247a65ec", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/74c3a6d7017021b0429296cb2429c3cccabf9230460492410480c7a36970b56a60e0942398c84b592a6581cb7914bd024ef2df5aec608b9635597771e3e70b28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7ca185347afc3498e796e2ad8c543b9689b854750307b603baa4a627467ffa90e308be845ab6c282048327be14324c29e4a9fcb9bcfd66187c6c5a93388a7dbf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 12:00:09.083457) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 12:00:09.088877) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1495737560.328684-2142526968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 12:00:09.138477) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 12:00:09.138852) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 12:00:09.139143) ========= ========= Total master_lag: 0.20 =========